-- Logs begin at Sat 2025-05-17 10:33:42 CEST, end at Sun 2025-05-18 08:52:49 CEST. -- May 18 08:51:02 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:02 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:11 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:11+02:00" level=debug msg="fetched chunk 5/15, size: 524288" uri="spotify:track:1zvVTYmpUAO1bXpV9Q9giy" May 18 08:51:12 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:12 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:12 teenieboombox volumio[1013]: info: Listing playlists May 18 08:51:12 teenieboombox volumio[1013]: info: Listing playlists May 18 08:51:12 teenieboombox systemd[1]: Started Checkmk agent updater. May 18 08:51:13 teenieboombox systemd[1]: cmk-update-agent.service: Succeeded. May 18 08:51:22 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:22 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:25 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:25+02:00" level=debug msg="fetched chunk 6/15, size: 524288" uri="spotify:track:1zvVTYmpUAO1bXpV9Q9giy" May 18 08:51:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:32 teenieboombox volumio[1013]: info: Listing playlists May 18 08:51:32 teenieboombox volumio[1013]: info: Listing playlists May 18 08:51:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:39+02:00" level=debug msg="fetched chunk 7/15, size: 524288" uri="spotify:track:1zvVTYmpUAO1bXpV9Q9giy" May 18 08:51:42 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:42 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioNext May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::next May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::stPlaybackTimer May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:46 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:46 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::serviceStop May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 3 May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceStop May 18 08:51:46 teenieboombox volumio[1013]: info: Spotify Stop May 18 08:51:46 teenieboombox volumio[1013]: SPOTIFY: SPOTIFY STOP May 18 08:51:46 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","position":3,"title":"Freed From Desire - Radio Edit","artist":"Gala","album":"25 Best Dance Hits Ever","albumart":"https://i.scdn.co/image/ab67616d0000b27365117541bacd8dad6870679d","uri":"spotify:track:1zvVTYmpUAO1bXpV9Q9giy","trackType":"spotify","codec":"ogg","seek":0,"duration":212,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":32,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 18 08:51:46 teenieboombox volumio[1013]: info: Sending Spotify command to local API: /player/pause May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::play index undefined May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:51:46 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:51:46 teenieboombox volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. May 18 08:51:46 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:46+02:00" level=debug msg="pause track at 61908ms" May 18 08:51:46 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:46 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:46 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:46+02:00" level=trace msg="emitting websocket event: paused" May 18 08:51:46 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1zvVTYmpUAO1bXpV9Q9giy","play_origin":"go-librespot"}} May 18 08:51:46 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:51:46 teenieboombox volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Freed From Desire - Radio Edit","artist":"Gala","album":"25 Best Dance Hits Ever","albumart":"https://i.scdn.co/image/ab67616d00001e0265117541bacd8dad6870679d","uri":"spotify:track:1zvVTYmpUAO1bXpV9Q9giy","trackType":"spotify","seek":60000,"duration":212,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 0 May 18 08:51:46 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Freed From Desire - Radio Edit","artist":"Gala","album":"25 Best Dance Hits Ever","albumart":"https://i.scdn.co/image/ab67616d00001e0265117541bacd8dad6870679d","uri":"spotify:track:1zvVTYmpUAO1bXpV9Q9giy","trackType":"spotify","seek":60000,"duration":212,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:46 teenieboombox volumio[1013]: verbose: CURRENT POSITION 0 May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService pause May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:51:46 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 0 May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:46 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:46 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 0 May 18 08:51:46 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:51:47 teenieboombox volumio[1013]: info: camilladsp spawned new process with pid 28996, instance 1, run: true May 18 08:51:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioNext May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::next May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::play index undefined May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:51:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::startPlaybackTimer May 18 08:51:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources May 18 08:51:47 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 08:51:47 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 18 08:51:47 teenieboombox volumio[1013]: info: [1747551107429] ControllerSpotify::clearAddPlayTrack May 18 08:51:47 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/play May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:51:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="resolved context of track" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=trace msg="emitting websocket event: will_play" May 18 08:51:47 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","play_origin":"go-librespot"}} May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="selected format OGG_VORBIS_320 (6dedbd774bb0ad5f8ddf13d34f4a58dd05af8b8d)" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="requested aes key for file 6dedbd774bb0ad5f8ddf13d34f4a58dd05af8b8d, gid: 6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="fetched first chunk of 15, total size is 7567655 bytes" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=info msg="loaded track \"Rock\" (paused: false, position: 0ms, duration: 144113ms, prefetched: false)" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=trace msg="scheduling prefetch in 114s" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=trace msg="emitting websocket event: metadata" May 18 08:51:47 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","name":"Rock","artist_names":["Stepz"],"album_name":"Rock","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025299e58cae4abdeebf3bab3a","position":0,"duration":144113,"release_date":"year:2024 month:10 day:11","track_number":1,"disc_number":1}} May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 18 08:51:47 teenieboombox volumio[1013]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=trace msg="emitting websocket event: playing" May 18 08:51:47 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","play_origin":"go-librespot"}} May 18 08:51:47 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:51:47 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Rock","artist":"Stepz","album":"Rock","albumart":"https://i.scdn.co/image/ab67616d00001e025299e58cae4abdeebf3bab3a","uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","trackType":"spotify","seek":0,"duration":144,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:47 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:51:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:47 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Rock","artist":"Stepz","album":"Rock","albumart":"https://i.scdn.co/image/ab67616d00001e025299e58cae4abdeebf3bab3a","uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","trackType":"spotify","seek":0,"duration":144,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:47 teenieboombox volumio[1013]: verbose: CURRENT POSITION 10 May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:51:47 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:51:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:47+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:6dXjUMjFxfAX4F2dHKqQzf" May 18 08:51:48 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:51:48 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Rock","artist":"Stepz","album":"Rock","albumart":"https://i.scdn.co/image/ab67616d00001e025299e58cae4abdeebf3bab3a","uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","trackType":"spotify","seek":0,"duration":144,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:48 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:51:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:48 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Rock","artist":"Stepz","album":"Rock","albumart":"https://i.scdn.co/image/ab67616d00001e025299e58cae4abdeebf3bab3a","uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","trackType":"spotify","seek":0,"duration":144,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:48 teenieboombox volumio[1013]: verbose: CURRENT POSITION 10 May 18 08:51:48 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:51:48 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:51:48 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:51:48 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:48 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:51:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:48 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:48 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:48 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:48 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:48 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:48 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:51:48 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:51:49 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioNext May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::next May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::stPlaybackTimer May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:51:49 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:49 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:49 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:51:49 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:49 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:49 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:49 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:49 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::serviceStop May 18 08:51:49 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 10 May 18 08:51:49 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceStop May 18 08:51:49 teenieboombox volumio[1013]: info: Spotify Stop May 18 08:51:49 teenieboombox volumio[1013]: SPOTIFY: SPOTIFY STOP May 18 08:51:49 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","position":10,"title":"Rock","artist":"Stepz","album":"Rock","albumart":"https://i.scdn.co/image/ab67616d0000b2735299e58cae4abdeebf3bab3a","uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","trackType":"spotify","codec":"ogg","seek":0,"duration":144,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":32,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 18 08:51:49 teenieboombox volumio[1013]: info: Sending Spotify command to local API: /player/pause May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::play index undefined May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:51:49 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::startPlaybackTimer May 18 08:51:49 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:49 teenieboombox volumio[1013]: info: [1747551109477] ControllerSpotify::clearAddPlayTrack May 18 08:51:49 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/play May 18 08:51:49 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:51:49 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:51:49 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="pause track at 2678ms" May 18 08:51:49 teenieboombox volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="resolved context of track" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=trace msg="emitting websocket event: will_play" May 18 08:51:49 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","play_origin":"go-librespot"}} May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="selected format OGG_VORBIS_320 (747f3538e53f5e20d23bede87d06307b509d641e)" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="requested aes key for file 747f3538e53f5e20d23bede87d06307b509d641e, gid: 3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="fetched first chunk of 22, total size is 11043902 bytes" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: May 18 08:51:49 teenieboombox volumio[1013]: error: FusionDsp - invalid sample rate May 18 08:51:49 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 08:51:49 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 08:51:49 teenieboombox volumio[1013]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=info msg="loaded track \"Future Remix 98\" (paused: false, position: 0ms, duration: 327809ms, prefetched: false)" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:49 teenieboombox volumio[1013]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=trace msg="scheduling prefetch in 298s" May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=trace msg="emitting websocket event: metadata" May 18 08:51:49 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","name":"Future Remix 98","artist_names":["Jog","CJ ROLO"],"album_name":"Future Remix 98","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0282cd138d59920632a7d1f8f9","position":0,"duration":327809,"release_date":"year:2010 month:1 day:24","track_number":1,"disc_number":1}} May 18 08:51:49 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:49+02:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:50 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:50+02:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:50 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:50 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:50+02:00" level=trace msg="emitting websocket event: paused" May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","play_origin":"go-librespot"}} May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Future Remix 98","artist":"Jog, CJ ROLO","album":"Future Remix 98","albumart":"https://i.scdn.co/image/ab67616d00001e0282cd138d59920632a7d1f8f9","uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","trackType":"spotify","seek":0,"duration":327,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Future Remix 98","artist":"Jog, CJ ROLO","album":"Future Remix 98","albumart":"https://i.scdn.co/image/ab67616d00001e0282cd138d59920632a7d1f8f9","uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","trackType":"spotify","seek":0,"duration":327,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:50 teenieboombox volumio[1013]: verbose: CURRENT POSITION 5 May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService pause May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:50 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:50 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:51:50 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:50+02:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:51:50 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:51:50 teenieboombox go-librespot[1397]: time="2025-05-18T08:51:50+02:00" level=trace msg="emitting websocket event: playing" May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","play_origin":"go-librespot"}} May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Future Remix 98","artist":"Jog, CJ ROLO","album":"Future Remix 98","albumart":"https://i.scdn.co/image/ab67616d00001e0282cd138d59920632a7d1f8f9","uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","trackType":"spotify","seek":0,"duration":327,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Future Remix 98","artist":"Jog, CJ ROLO","album":"Future Remix 98","albumart":"https://i.scdn.co/image/ab67616d00001e0282cd138d59920632a7d1f8f9","uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","trackType":"spotify","seek":0,"duration":327,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:50 teenieboombox volumio[1013]: verbose: CURRENT POSITION 5 May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:51:50 teenieboombox volumio[1013]: info: camilladsp spawned new process with pid 29025, instance 1, run: true May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Future Remix 98","artist":"Jog, CJ ROLO","album":"Future Remix 98","albumart":"https://i.scdn.co/image/ab67616d00001e0282cd138d59920632a7d1f8f9","uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","trackType":"spotify","seek":0,"duration":327,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Future Remix 98","artist":"Jog, CJ ROLO","album":"Future Remix 98","albumart":"https://i.scdn.co/image/ab67616d00001e0282cd138d59920632a7d1f8f9","uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","trackType":"spotify","seek":0,"duration":327,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:51:50 teenieboombox volumio[1013]: verbose: CURRENT POSITION 5 May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:51:50 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:50 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:50 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:51:50 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:51:50 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:51:50 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:50 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:50 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:51:52 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:51:52 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:51:52 teenieboombox volumio[1013]: info: Listing playlists May 18 08:51:52 teenieboombox volumio[1013]: info: Listing playlists May 18 08:51:56 teenieboombox systemd[1]: Started Checkmk agent (10.10.20.2:36934). May 18 08:51:56 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:51:56 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:51:57 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:51:58 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:51:58 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:51:59 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:51:59 teenieboombox systemd[1]: check-mk-agent@1377-10.10.40.102:6556-10.10.20.2:36934.service: Succeeded. May 18 08:52:02 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:02 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:52:05 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:05+02:00" level=debug msg="fetched chunk 4/21, size: 524288" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:52:10 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:52:10 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:52:11 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:52:12 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:12 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:52:12 teenieboombox volumio[1013]: info: Listing playlists May 18 08:52:12 teenieboombox volumio[1013]: info: Listing playlists May 18 08:52:12 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:52:12 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:52:12 teenieboombox systemd[1]: Started Checkmk agent updater. May 18 08:52:13 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:52:13 teenieboombox systemd[1]: cmk-update-agent.service: Succeeded. May 18 08:52:16 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:52:16 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:52:16 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:52:17 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:52:17 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:52:18 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:52:19 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:52:19 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:52:19 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:52:20 teenieboombox volumio[1013]: Searching plugin music_service/spop May 18 08:52:20 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: spop , search May 18 08:52:20 teenieboombox volumio[1013]: info: All search sources collected, pushing search results May 18 08:52:21 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:21+02:00" level=debug msg="fetched chunk 5/21, size: 524288" uri="spotify:track:3qhArZJLBv2ivxjEifc7wK" May 18 08:52:22 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:22 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:52:32 teenieboombox volumio[1013]: info: Preload queue cleared May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioReplaceandPlayItems May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::ClearQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::stPlaybackTimer May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:32 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:32 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::serviceStop May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceStop May 18 08:52:32 teenieboombox volumio[1013]: info: Spotify Stop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: SPOTIFY STOP May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","position":5,"title":"Future Remix 98","artist":"Jog","album":"Future Remix 98","albumart":"https://i.scdn.co/image/ab67616d0000b27382cd138d59920632a7d1f8f9","uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","trackType":"spotify","codec":"ogg","seek":0,"duration":327,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":32,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 18 08:52:32 teenieboombox volumio[1013]: info: Sending Spotify command to local API: /player/pause May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::clearPlayQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::saveQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: Preload queue cleared May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:6dXjUMjFxfAX4F2dHKqQzf May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:6dXjUMjFxfAX4F2dHKqQzf in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:6dXjUMjFxfAX4F2dHKqQzf May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2vNwp6lpQFIWCE6VIZpk1o May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2vNwp6lpQFIWCE6VIZpk1o in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2vNwp6lpQFIWCE6VIZpk1o May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:32 teenieboombox volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="pause track at 43120ms" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6dXjUMjFxfAX4F2dHKqQzf","service":"spop","name":"Rock","artist":"Stepz","album":"Rock","type":"song","duration":144,"albumart":"https://i.scdn.co/image/ab67616d0000b2735299e58cae4abdeebf3bab3a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 5 May 18 08:52:32 teenieboombox volumio[1013]: info: Listing playlists May 18 08:52:32 teenieboombox volumio[1013]: info: Listing playlists May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","service":"spop","name":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b27310acde02b9bf4d72b3fad505","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::saveQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPlay May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::play index 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: Preload queue cleared May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:00Srvq2BtgG9afXY9kzB1V May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:00Srvq2BtgG9afXY9kzB1V in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:00Srvq2BtgG9afXY9kzB1V May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:4Pt80KvnL6e8DCd9QVx7Ht May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:4Pt80KvnL6e8DCd9QVx7Ht in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:4Pt80KvnL6e8DCd9QVx7Ht May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:7w3tRoXSCzCQZR3H46i25C May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:7w3tRoXSCzCQZR3H46i25C in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:7w3tRoXSCzCQZR3H46i25C May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2ToHbzCV6ySSwtXRL8GcBS May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2ToHbzCV6ySSwtXRL8GcBS in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2ToHbzCV6ySSwtXRL8GcBS May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:70PgtZch24fK6Dg5VahpFk May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:70PgtZch24fK6Dg5VahpFk in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:70PgtZch24fK6Dg5VahpFk May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:42St6sNspeRDmW9H7UNlAP May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:42St6sNspeRDmW9H7UNlAP in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:42St6sNspeRDmW9H7UNlAP May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:4FKblZA8omKziWiHZD8RTq May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:4FKblZA8omKziWiHZD8RTq in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:4FKblZA8omKziWiHZD8RTq May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:6azx6iv2R1DhwovSp4xd5v May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:6azx6iv2R1DhwovSp4xd5v in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:6azx6iv2R1DhwovSp4xd5v May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2ntw401pdhJCTDKRBAT2ZP May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2ntw401pdhJCTDKRBAT2ZP in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2ntw401pdhJCTDKRBAT2ZP May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:7I9cYR8tnejdB1Z8G76S00 May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:7I9cYR8tnejdB1Z8G76S00 in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:7I9cYR8tnejdB1Z8G76S00 May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:5vUJ7PG0aY89m04btGQPih May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:5vUJ7PG0aY89m04btGQPih in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:5vUJ7PG0aY89m04btGQPih May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2Uip5SNGObHuyBrHXHUUjh May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2Uip5SNGObHuyBrHXHUUjh in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2Uip5SNGObHuyBrHXHUUjh May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2omzZ5Edn2sMpQxvARgbwj May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2omzZ5Edn2sMpQxvARgbwj in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2omzZ5Edn2sMpQxvARgbwj May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=trace msg="emitting websocket event: paused" May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:6pPZJSXUHota9i3fANxhQo May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:6pPZJSXUHota9i3fANxhQo in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:6pPZJSXUHota9i3fANxhQo May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:4xPW8VJCP4MTvfFsFaBHTP May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:4xPW8VJCP4MTvfFsFaBHTP in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:4xPW8VJCP4MTvfFsFaBHTP May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:56hNOnvyy1OXcl4MkjnGvI May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:56hNOnvyy1OXcl4MkjnGvI in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:56hNOnvyy1OXcl4MkjnGvI May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:3mzKC8f0aUKkjACUzbcL5Y May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:3mzKC8f0aUKkjACUzbcL5Y in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:3mzKC8f0aUKkjACUzbcL5Y May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:0ee6O6GziVBNX8RoZZDgQ7 May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:0ee6O6GziVBNX8RoZZDgQ7 in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:0ee6O6GziVBNX8RoZZDgQ7 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::play index undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::startPlaybackTimer May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 18 08:52:32 teenieboombox volumio[1013]: info: [1747551152591] ControllerSpotify::clearAddPlayTrack May 18 08:52:32 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/play May 18 08:52:32 teenieboombox volumio[1013]: info: Preload queue cleared May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioReplaceandPlayItems May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::ClearQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::clearPlayQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::saveQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: Preload queue cleared May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:6dXjUMjFxfAX4F2dHKqQzf May 18 08:52:32 teenieboombox volumio[1013]: info: Using cached record of: spotify:track:6dXjUMjFxfAX4F2dHKqQzf May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2vNwp6lpQFIWCE6VIZpk1o May 18 08:52:32 teenieboombox volumio[1013]: info: Using cached record of: spotify:track:2vNwp6lpQFIWCE6VIZpk1o May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::saveQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPlay May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::play index 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::addQueueItems May 18 08:52:32 teenieboombox volumio[1013]: info: Preload queue cleared May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:00Srvq2BtgG9afXY9kzB1V May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:00Srvq2BtgG9afXY9kzB1V in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:00Srvq2BtgG9afXY9kzB1V May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:4Pt80KvnL6e8DCd9QVx7Ht May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:4Pt80KvnL6e8DCd9QVx7Ht in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:4Pt80KvnL6e8DCd9QVx7Ht May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:7w3tRoXSCzCQZR3H46i25C May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:7w3tRoXSCzCQZR3H46i25C in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:7w3tRoXSCzCQZR3H46i25C May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2ToHbzCV6ySSwtXRL8GcBS May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2ToHbzCV6ySSwtXRL8GcBS in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2ToHbzCV6ySSwtXRL8GcBS May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:70PgtZch24fK6Dg5VahpFk May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:70PgtZch24fK6Dg5VahpFk in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:70PgtZch24fK6Dg5VahpFk May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:42St6sNspeRDmW9H7UNlAP May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:42St6sNspeRDmW9H7UNlAP in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:42St6sNspeRDmW9H7UNlAP May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:4FKblZA8omKziWiHZD8RTq May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:4FKblZA8omKziWiHZD8RTq in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:4FKblZA8omKziWiHZD8RTq May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:6azx6iv2R1DhwovSp4xd5v May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:6azx6iv2R1DhwovSp4xd5v in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:6azx6iv2R1DhwovSp4xd5v May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2ntw401pdhJCTDKRBAT2ZP May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2ntw401pdhJCTDKRBAT2ZP in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2ntw401pdhJCTDKRBAT2ZP May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:7I9cYR8tnejdB1Z8G76S00 May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:7I9cYR8tnejdB1Z8G76S00 in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:7I9cYR8tnejdB1Z8G76S00 May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:5vUJ7PG0aY89m04btGQPih May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:5vUJ7PG0aY89m04btGQPih in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:5vUJ7PG0aY89m04btGQPih May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2Uip5SNGObHuyBrHXHUUjh May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2Uip5SNGObHuyBrHXHUUjh in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2Uip5SNGObHuyBrHXHUUjh May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:2omzZ5Edn2sMpQxvARgbwj May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:2omzZ5Edn2sMpQxvARgbwj in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:2omzZ5Edn2sMpQxvARgbwj May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:6pPZJSXUHota9i3fANxhQo May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:6pPZJSXUHota9i3fANxhQo in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:6pPZJSXUHota9i3fANxhQo May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:4xPW8VJCP4MTvfFsFaBHTP May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:4xPW8VJCP4MTvfFsFaBHTP in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:4xPW8VJCP4MTvfFsFaBHTP May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:56hNOnvyy1OXcl4MkjnGvI May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:56hNOnvyy1OXcl4MkjnGvI in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:56hNOnvyy1OXcl4MkjnGvI May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:3mzKC8f0aUKkjACUzbcL5Y May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:3mzKC8f0aUKkjACUzbcL5Y in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:3mzKC8f0aUKkjACUzbcL5Y May 18 08:52:32 teenieboombox volumio[1013]: info: Adding Item to queue: spotify:track:0ee6O6GziVBNX8RoZZDgQ7 May 18 08:52:32 teenieboombox volumio[1013]: info: Exploding uri spotify:track:0ee6O6GziVBNX8RoZZDgQ7 in service spop May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: EXPLODING URI:spotify:track:0ee6O6GziVBNX8RoZZDgQ7 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::play index undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::startPlaybackTimer May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 18 08:52:32 teenieboombox volumio[1013]: info: [1747551152647] ControllerSpotify::clearAddPlayTrack May 18 08:52:32 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/play May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3qhArZJLBv2ivxjEifc7wK","play_origin":"go-librespot"}} May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:32 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:32 teenieboombox volumio[1013]: verbose: CURRENT POSITION 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService pause May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:32 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:32 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="resolved context of track" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=trace msg="emitting websocket event: will_play" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="selected format OGG_VORBIS_320 (dc280d6dfb2ec7c1f661379cb27cf0865f30820a)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:32 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:32+02:00" level=debug msg="requested aes key for file dc280d6dfb2ec7c1f661379cb27cf0865f30820a, gid: 2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","play_origin":"go-librespot"}} May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ToHbzCV6ySSwtXRL8GcBS","service":"spop","name":"Rock-Stepz - Sped Up","artist":"RestartR","album":"Rock-Stepz (Sped Up)","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b273876c75a744caf2eca0311612","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42St6sNspeRDmW9H7UNlAP","service":"spop","name":"Desert Rock","artist":"Stepz","album":"Saudi Vision","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b273183506ac7f41f4832434c875","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4FKblZA8omKziWiHZD8RTq","service":"spop","name":"Rocksteady","artist":"The Bloody Beetroots","album":"Sundown (Original Motion Picture Soundtrack)","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273b9b49e11904a8c231a7ce3a7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:00Srvq2BtgG9afXY9kzB1V","service":"spop","name":"GANG","artist":"Kairo Keyz","album":"GANG","type":"song","duration":113,"albumart":"https://i.scdn.co/image/ab67616d0000b273169e1e934ced04dcf5ef44b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:56hNOnvyy1OXcl4MkjnGvI","service":"spop","name":"Licht 2K14","artist":"Rockstroh","album":"Licht 2K14","type":"song","duration":428,"albumart":"https://i.scdn.co/image/ab67616d0000b273dba12dc19dca892b60168b2f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:70PgtZch24fK6Dg5VahpFk","service":"spop","name":"Rock Stepz","artist":"Dirty Pop","album":"Rock Stepz","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273e9a67b1cbe5e92d044133341","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ntw401pdhJCTDKRBAT2ZP","service":"spop","name":"Rock Steady","artist":"Tay Iwar","album":"GOLD II","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b2736970c065058a087144b31d49","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3mzKC8f0aUKkjACUzbcL5Y","service":"spop","name":"Ich gehör Dir nicht - MartinBepunkt Remix","artist":"Rockstroh","album":"Ich gehör Dir nicht","type":"song","duration":139,"albumart":"https://i.scdn.co/image/ab67616d0000b273af4a10c7efc8b4fb3c8b3c89","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7w3tRoXSCzCQZR3H46i25C","service":"spop","name":"rock - sped up","artist":"badkarma","album":"rock (sped up)","type":"song","duration":130,"albumart":"https://i.scdn.co/image/ab67616d0000b273dcdb7bf8d79b15c63c727176","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Pt80KvnL6e8DCd9QVx7Ht","service":"spop","name":"Pink Lemonade (Str8 Reload)","artist":"LeoStayTrill","album":"Pink Lemonade (Str8 Reload)","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b27336c18e04657dca159c6b9e3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6azx6iv2R1DhwovSp4xd5v","service":"spop","name":"Rock Steady","artist":"Alton Ellis","album":"Mr Soul of Jamaica","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273fb791984c330ba13085cb721","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","service":"spop","name":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b2738abe7e02e066d2e695ec2205","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vUJ7PG0aY89m04btGQPih","service":"spop","name":"Rock Steady","artist":"YOUNITE","album":"YOUNI-T","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b27335c2018600fc3bef496bfc7a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6pPZJSXUHota9i3fANxhQo","service":"spop","name":"Licht 2K14 - Original 2009 Radio Mix","artist":"Rockstroh","album":"Herzpirat (Gold Edition)","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273c1f0c13d81b673a6cbd03bd7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4xPW8VJCP4MTvfFsFaBHTP","service":"spop","name":"Tanzen 2024","artist":"Rockstroh","album":"Tanzen 2024","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b2734051120bc80d64f6faa7a87d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Uip5SNGObHuyBrHXHUUjh","service":"spop","name":"Tanzen - Radio Mix","artist":"Rockstroh","album":"Herzpirat (Gold Edition)","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b273c1f0c13d81b673a6cbd03bd7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2omzZ5Edn2sMpQxvARgbwj","service":"spop","name":"Baby Steps","artist":"David Puentez","album":"Baby Steps","type":"song","duration":143,"albumart":"https://i.scdn.co/image/ab67616d0000b2732d1adf0f9aa743e1a1fa5615","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ee6O6GziVBNX8RoZZDgQ7","service":"spop","name":"Licht 2K22","artist":"Rockstroh","album":"Licht 2K22","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b273824a7a7eb02a5df14c505bbc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:32 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::saveQueue May 18 08:52:32 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:32 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched first chunk of 11, total size is 5591711 bytes" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 08:52:33 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 08:52:33 teenieboombox volumio[1013]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=info msg="loaded track \"rock - slowed + reverb\" (paused: false, position: 0ms, duration: 165578ms, prefetched: false)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 08:52:33 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 08:52:33 teenieboombox volumio[1013]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="scheduling prefetch in 135s" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="emitting websocket event: metadata" May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","name":"rock - slowed + reverb","artist_names":["badkarma"],"album_name":"rock (slowed + reverb)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","position":0,"duration":165578,"release_date":"year:2024 month:11 day:1","track_number":1,"disc_number":1}} May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="resolved context of track" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="emitting websocket event: will_play" May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","play_origin":"go-librespot"}} May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="selected format OGG_VORBIS_320 (dc280d6dfb2ec7c1f661379cb27cf0865f30820a)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="requested aes key for file dc280d6dfb2ec7c1f661379cb27cf0865f30820a, gid: 2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox volumio[1013]: info: camilladsp spawned new process with pid 29308, instance 1, run: true May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched first chunk of 11, total size is 5591711 bytes" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=info msg="loaded track \"rock - slowed + reverb\" (paused: false, position: 0ms, duration: 165578ms, prefetched: false)" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="scheduling prefetch in 135s" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="emitting websocket event: metadata" May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","name":"rock - slowed + reverb","artist_names":["badkarma"],"album_name":"rock (slowed + reverb)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","position":0,"duration":165578,"release_date":"year:2024 month:11 day:1","track_number":1,"disc_number":1}} May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="emitting websocket event: playing" May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","play_origin":"go-librespot"}} May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: verbose: CURRENT POSITION 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:2vNwp6lpQFIWCE6VIZpk1o" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:33 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:33+02:00" level=trace msg="emitting websocket event: playing" May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","play_origin":"go-librespot"}} May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: verbose: CURRENT POSITION 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:52:33 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: verbose: CURRENT POSITION 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:52:33 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d00001e0210acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","seek":0,"duration":165,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:33 teenieboombox volumio[1013]: verbose: CURRENT POSITION 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:52:33 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:33 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:33 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:33 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:33 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:38 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioNext May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::next May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::stPlaybackTimer May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:38 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:38 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:38 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:38 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:38 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:38 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:38 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:38 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::serviceStop May 18 08:52:38 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 1 May 18 08:52:38 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceStop May 18 08:52:38 teenieboombox volumio[1013]: info: Spotify Stop May 18 08:52:38 teenieboombox volumio[1013]: SPOTIFY: SPOTIFY STOP May 18 08:52:38 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","position":1,"title":"rock - slowed + reverb","artist":"badkarma","album":"rock (slowed + reverb)","albumart":"https://i.scdn.co/image/ab67616d0000b27310acde02b9bf4d72b3fad505","uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","trackType":"spotify","codec":"ogg","seek":0,"duration":165,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":32,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 18 08:52:38 teenieboombox volumio[1013]: info: Sending Spotify command to local API: /player/pause May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::play index undefined May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:38 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::startPlaybackTimer May 18 08:52:38 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:38 teenieboombox volumio[1013]: info: [1747551158930] ControllerSpotify::clearAddPlayTrack May 18 08:52:38 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/play May 18 08:52:38 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:38 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:38 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:38 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:38+02:00" level=debug msg="pause track at 5889ms" May 18 08:52:38 teenieboombox volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=trace msg="emitting websocket event: paused" May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2vNwp6lpQFIWCE6VIZpk1o","play_origin":"go-librespot"}} May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:39 teenieboombox volumio[1013]: verbose: CURRENT POSITION 11 May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService pause May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:39 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:39 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="resolved context of track" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=trace msg="emitting websocket event: will_play" May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","play_origin":"go-librespot"}} May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="selected format OGG_VORBIS_320 (4c9eecef82300bd66f77c4a2a70c69164b2fdd02)" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="requested aes key for file 4c9eecef82300bd66f77c4a2a70c69164b2fdd02, gid: 7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="fetched first chunk of 19, total size is 9707824 bytes" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: May 18 08:52:39 teenieboombox volumio[1013]: error: FusionDsp - invalid sample rate May 18 08:52:39 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 08:52:39 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 08:52:39 teenieboombox volumio[1013]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=info msg="loaded track \"(Hey You) The Rock Steady Crew\" (paused: false, position: 0ms, duration: 227293ms, prefetched: false)" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=trace msg="scheduling prefetch in 197s" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=trace msg="emitting websocket event: metadata" May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","name":"(Hey You) The Rock Steady Crew","artist_names":["Rock Steady Crew"],"album_name":"Fetenhits - One Hit Wonder","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","position":0,"duration":227293,"release_date":"year:2022 month:9 day:30","track_number":50,"disc_number":1}} May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=trace msg="emitting websocket event: playing" May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","play_origin":"go-librespot"}} May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:39 teenieboombox volumio[1013]: verbose: CURRENT POSITION 11 May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:52:39 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:39+02:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:39 teenieboombox volumio[1013]: info: camilladsp spawned new process with pid 29335, instance 1, run: true May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:39 teenieboombox volumio[1013]: verbose: CURRENT POSITION 11 May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:52:39 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:39 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:39 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:39 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:39 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:39 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:39 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:39 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:40 teenieboombox volumio[1013]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 18 08:52:40 teenieboombox volumio[1013]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 18 08:52:40 teenieboombox volumio[1013]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:00Srvq2BtgG9afXY9kzB1V","service":"spop","name":"GANG","artist":"Kairo Keyz","album":"GANG","type":"song","duration":113,"albumart":"https://i.scdn.co/image/ab67616d0000b273169e1e934ced04dcf5ef44b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4FKblZA8omKziWiHZD8RTq","service":"spop","name":"Rocksteady","artist":"The Bloody Beetroots","album":"Sundown (Original Motion Picture Soundtrack)","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273b9b49e11904a8c231a7ce3a7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6azx6iv2R1DhwovSp4xd5v","service":"spop","name":"Rock Steady","artist":"Alton Ellis","album":"Mr Soul of Jamaica","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273fb791984c330ba13085cb721","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7w3tRoXSCzCQZR3H46i25C","service":"spop","name":"rock - sped up","artist":"badkarma","album":"rock (sped up)","type":"song","duration":130,"albumart":"https://i.scdn.co/image/ab67616d0000b273dcdb7bf8d79b15c63c727176","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4xPW8VJCP4MTvfFsFaBHTP","service":"spop","name":"Tanzen 2024","artist":"Rockstroh","album":"Tanzen 2024","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b2734051120bc80d64f6faa7a87d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Pt80KvnL6e8DCd9QVx7Ht","service":"spop","name":"Pink Lemonade (Str8 Reload)","artist":"LeoStayTrill","album":"Pink Lemonade (Str8 Reload)","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b27336c18e04657dca159c6b9e3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Uip5SNGObHuyBrHXHUUjh","service":"spop","name":"Tanzen - Radio Mix","artist":"Rockstroh","album":"Herzpirat (Gold Edition)","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b273c1f0c13d81b673a6cbd03bd7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3mzKC8f0aUKkjACUzbcL5Y","service":"spop","name":"Ich gehör Dir nicht - MartinBepunkt Remix","artist":"Rockstroh","album":"Ich gehör Dir nicht","type":"song","duration":139,"albumart":"https://i.scdn.co/image/ab67616d0000b273af4a10c7efc8b4fb3c8b3c89","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42St6sNspeRDmW9H7UNlAP","service":"spop","name":"Desert Rock","artist":"Stepz","album":"Saudi Vision","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b273183506ac7f41f4832434c875","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ToHbzCV6ySSwtXRL8GcBS","service":"spop","name":"Rock-Stepz - Sped Up","artist":"RestartR","album":"Rock-Stepz (Sped Up)","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b273876c75a744caf2eca0311612","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:70PgtZch24fK6Dg5VahpFk","service":"spop","name":"Rock Stepz","artist":"Dirty Pop","album":"Rock Stepz","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273e9a67b1cbe5e92d044133341","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:56hNOnvyy1OXcl4MkjnGvI","service":"spop","name":"Licht 2K14","artist":"Rockstroh","album":"Licht 2K14","type":"song","duration":428,"albumart":"https://i.scdn.co/image/ab67616d0000b273dba12dc19dca892b60168b2f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vUJ7PG0aY89m04btGQPih","service":"spop","name":"Rock Steady","artist":"YOUNITE","album":"YOUNI-T","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b27335c2018600fc3bef496bfc7a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ee6O6GziVBNX8RoZZDgQ7","service":"spop","name":"Licht 2K22","artist":"Rockstroh","album":"Licht 2K22","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b273824a7a7eb02a5df14c505bbc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ntw401pdhJCTDKRBAT2ZP","service":"spop","name":"Rock Steady","artist":"Tay Iwar","album":"GOLD II","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b2736970c065058a087144b31d49","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6pPZJSXUHota9i3fANxhQo","service":"spop","name":"Licht 2K14 - Original 2009 Radio Mix","artist":"Rockstroh","album":"Herzpirat (Gold Edition)","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273c1f0c13d81b673a6cbd03bd7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2omzZ5Edn2sMpQxvARgbwj","service":"spop","name":"Baby Steps","artist":"David Puentez","album":"Baby Steps","type":"song","duration":143,"albumart":"https://i.scdn.co/image/ab67616d0000b2732d1adf0f9aa743e1a1fa5615","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","service":"spop","name":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b2738abe7e02e066d2e695ec2205","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 18 08:52:40 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushQueue May 18 08:52:40 teenieboombox volumio[1013]: info: CorePlayQueue::saveQueue May 18 08:52:40 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:40 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:41 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioRandom May 18 08:52:41 teenieboombox volumio[1013]: info: CoreCommandRouter::writePlayerControls May 18 08:52:41 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:41 teenieboombox volumio[1013]: info: CoreStateMachine::setRepeat true single false May 18 08:52:41 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:41 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:41 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:41 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:41 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:41 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:41 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:41 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:41 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:42 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:42 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:43 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:43 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:43 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:43 teenieboombox volumio[1013]: info: Spotify seek to: 0 May 18 08:52:43 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/seek May 18 08:52:43 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:43+02:00" level=debug msg="seek track to 0ms" May 18 08:52:43 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:43+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:43 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:43+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:43 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:43+02:00" level=trace msg="scheduling prefetch in 197s" May 18 08:52:43 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:43+02:00" level=trace msg="emitting websocket event: seek" May 18 08:52:43 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","position":0,"duration":227293,"play_origin":"go-librespot"}} May 18 08:52:43 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:43 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:43 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:43 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:43 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:43 teenieboombox volumio[1013]: verbose: CURRENT POSITION 11 May 18 08:52:43 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:43 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:52:43 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:52:43 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:43 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:43 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:43 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:43 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:43 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:43 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:43 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:43 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:43 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:43 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:43 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:43 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:43 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:43 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:43 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:43 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:44 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioRandom May 18 08:52:44 teenieboombox volumio[1013]: info: CoreCommandRouter::writePlayerControls May 18 08:52:44 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:44 teenieboombox volumio[1013]: info: CoreStateMachine::setRepeat true single true May 18 08:52:44 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:44 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:44 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:44 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:44 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:44 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:44 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:44 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:44 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , setInfinityPlayback May 18 08:52:45 teenieboombox volumio[1013]: info: METAVOLUMIO: Enabling Infinity Playback May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:45 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioRandom May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::writePlayerControls May 18 08:52:45 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:45 teenieboombox volumio[1013]: info: CoreStateMachine::setRepeat false single false May 18 08:52:45 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:45 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:45 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:45 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:45 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:45 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:45 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:46 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , setInfinityPlayback May 18 08:52:46 teenieboombox volumio[1013]: info: METAVOLUMIO: Disabling Infinity Playback May 18 08:52:46 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 08:52:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioRandom May 18 08:52:46 teenieboombox volumio[1013]: info: CoreCommandRouter::writePlayerControls May 18 08:52:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:46 teenieboombox volumio[1013]: info: CoreStateMachine::setRepeat false single false May 18 08:52:46 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:46 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:46 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:46 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:46 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:46 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:46 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: Spotify seek to: 0 May 18 08:52:47 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/seek May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=debug msg="seek track to 0ms" May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7I9cYR8tnejdB1Z8G76S00" May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=trace msg="scheduling prefetch in 197s" May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=trace msg="emitting websocket event: seek" May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","position":0,"duration":227293,"play_origin":"go-librespot"}} May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":0,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:47 teenieboombox volumio[1013]: verbose: CURRENT POSITION 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus play May 18 08:52:47 teenieboombox volumio[1013]: info: Received an update from plugin. extracting info from payload May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::stop May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::stPlaybackTimer May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::serviceStop May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceStop May 18 08:52:47 teenieboombox volumio[1013]: info: Spotify Stop May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: SPOTIFY STOP May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","position":11,"title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d0000b2738abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","codec":"ogg","seek":0,"duration":227,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":32,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 18 08:52:47 teenieboombox volumio[1013]: info: Sending Spotify command to local API: /player/pause May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=debug msg="pause track at 639ms" May 18 08:52:47 teenieboombox volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:47 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:47+02:00" level=trace msg="emitting websocket event: paused" May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","play_origin":"go-librespot"}} May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":1000,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"(Hey You) The Rock Steady Crew","artist":"Rock Steady Crew","album":"Fetenhits - One Hit Wonder","albumart":"https://i.scdn.co/image/ab67616d00001e028abe7e02e066d2e695ec2205","uri":"spotify:track:7I9cYR8tnejdB1Z8G76S00","trackType":"spotify","seek":1000,"duration":227,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:47 teenieboombox volumio[1013]: verbose: CURRENT POSITION 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService pause May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:52:47 teenieboombox volumio[1013]: info: CoreStateMachine::pushState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPushState May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output update for this device May 18 08:52:47 teenieboombox volumio[1013]: info: MRS: Pushing multiroomSync output May 18 08:52:47 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioGetState May 18 08:52:47 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 11 May 18 08:52:47 teenieboombox volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::serviceClearAddPlay May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceClearAddPlayTracks May 18 08:52:48 teenieboombox volumio[1013]: error: WARNING: No clearAddPlayTracks method for service spop May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::play index undefined May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 18 May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::startPlaybackTimer May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 18 May 18 08:52:48 teenieboombox volumio[1013]: info: [1747551168268] ControllerSpotify::clearAddPlayTrack May 18 08:52:48 teenieboombox volumio[1013]: info: Sending Spotify command with payload to local API: /player/play May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="resolved context of track" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::serviceClearAddPlay May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceClearAddPlayTracks May 18 08:52:48 teenieboombox volumio[1013]: error: WARNING: No clearAddPlayTracks method for service spop May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=trace msg="emitting websocket event: will_play" May 18 08:52:48 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3mzKC8f0aUKkjACUzbcL5Y","play_origin":"go-librespot"}} May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="selected format OGG_VORBIS_320 (4a8138984e3a8e2b9646ac62a060d7e659387708)" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="requested aes key for file 4a8138984e3a8e2b9646ac62a060d7e659387708, gid: 3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="fetched first chunk of 12, total size is 6101904 bytes" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::serviceClearAddPlay May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceClearAddPlayTracks May 18 08:52:48 teenieboombox volumio[1013]: error: WARNING: No clearAddPlayTracks method for service spop May 18 08:52:48 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 18 08:52:48 teenieboombox volumio[1013]: info: FusionDsp - ---- read samplerate from file: 44100 May 18 08:52:48 teenieboombox volumio[1013]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=info msg="loaded track \"Ich gehör Dir nicht - MartinBepunkt Remix\" (paused: false, position: 0ms, duration: 139691ms, prefetched: false)" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox volumio[1013]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=trace msg="scheduling prefetch in 109s" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=trace msg="emitting websocket event: metadata" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="fetched chunk 3/11, size: 524288" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox volumio[1013]: info: camilladsp spawned new process with pid 29418, instance 1, run: true May 18 08:52:48 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3mzKC8f0aUKkjACUzbcL5Y","name":"Ich gehör Dir nicht - MartinBepunkt Remix","artist_names":["Rockstroh","MartinBepunkt"],"album_name":"Ich gehör Dir nicht","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02af4a10c7efc8b4fb3c8b3c89","position":0,"duration":139691,"release_date":"year:2021 month:11 day:19","track_number":2,"disc_number":1}} May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="fetched chunk 1/11, size: 524288" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::updateTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrackBlock May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::serviceClearAddPlay May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::serviceClearAddPlayTracks May 18 08:52:48 teenieboombox volumio[1013]: error: WARNING: No clearAddPlayTracks method for service spop May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=trace msg="emitting websocket event: playing" May 18 08:52:48 teenieboombox volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3mzKC8f0aUKkjACUzbcL5Y","play_origin":"go-librespot"}} May 18 08:52:48 teenieboombox volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY May 18 08:52:48 teenieboombox volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Ich gehör Dir nicht - MartinBepunkt Remix","artist":"Rockstroh, MartinBepunkt","album":"Ich gehör Dir nicht","albumart":"https://i.scdn.co/image/ab67616d00001e02af4a10c7efc8b4fb3c8b3c89","uri":"spotify:track:3mzKC8f0aUKkjACUzbcL5Y","trackType":"spotify","seek":0,"duration":139,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::servicePushState May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 20 May 18 08:52:48 teenieboombox volumio[1013]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ich gehör Dir nicht - MartinBepunkt Remix","artist":"Rockstroh, MartinBepunkt","album":"Ich gehör Dir nicht","albumart":"https://i.scdn.co/image/ab67616d00001e02af4a10c7efc8b4fb3c8b3c89","uri":"spotify:track:3mzKC8f0aUKkjACUzbcL5Y","trackType":"spotify","seek":0,"duration":139,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 18 08:52:48 teenieboombox volumio[1013]: verbose: CURRENT POSITION 20 May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::syncState stateService play May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::syncState currentStatus stop May 18 08:52:48 teenieboombox go-librespot[1397]: time="2025-05-18T08:52:48+02:00" level=debug msg="fetched chunk 2/11, size: 524288" uri="spotify:track:3mzKC8f0aUKkjACUzbcL5Y" May 18 08:52:48 teenieboombox volumio[1013]: info: CoreCommandRouter::volumioPrevious May 18 08:52:48 teenieboombox volumio[1013]: info: CoreStateMachine::previous May 18 08:52:48 teenieboombox volumio[1013]: info: CorePlayQueue::getTrack 20 May 18 08:52:48 teenieboombox volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 08:52:48 teenieboombox volumio[1013]: TypeError: Cannot read property 'service' of undefined May 18 08:52:48 teenieboombox volumio[1013]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1325:97) May 18 08:52:48 teenieboombox volumio[1013]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:97:28) May 18 08:52:48 teenieboombox volumio[1013]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) May 18 08:52:48 teenieboombox volumio[1013]: at Socket.emit (events.js:315:20) May 18 08:52:48 teenieboombox volumio[1013]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 18 08:52:48 teenieboombox volumio[1013]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 18 08:52:48 teenieboombox volumio[1013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 08:52:49 teenieboombox sudo[29435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 08:51 May 18 08:52:49 teenieboombox sudo[29435]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"