-- Logs begin at Mon 2025-02-17 15:52:51 UTC, end at Mon 2025-02-17 18:57:19 UTC. -- Feb 17 18:56:00 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:00 znt volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::ClearQueue Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::clearPlayQueue Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:00 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:00 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:6ZHaVH17yrzdIH6y7mQCcG Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:6ZHaVH17yrzdIH6y7mQCcG Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:1VxyYssav9Zo9ekm2Y7bfB Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:1VxyYssav9Zo9ekm2Y7bfB Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:64H8QKpQW7keYpkP2PAMJD Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:64H8QKpQW7keYpkP2PAMJD Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:5dr4xwJvEmbJ4DFEGPjoL7 Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:5dr4xwJvEmbJ4DFEGPjoL7 Feb 17 18:56:00 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:00 znt volumio[1197]: info: CoreCommandRouter::volumioPlay Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::play index 3 Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:00 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:6jAJJKuaMDuuQ0L6gzF6xX Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:6jAJJKuaMDuuQ0L6gzF6xX Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:1NpujS48kC7LccLratOh60 Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:1NpujS48kC7LccLratOh60 Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:1pk4WIiSJo34McKJpHl8vR Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:1pk4WIiSJo34McKJpHl8vR Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:3NWOwmnUQ3Sl00hLzy7Oqb Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:3NWOwmnUQ3Sl00hLzy7Oqb Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:68PfAkWhB3aNsu1VKmmABZ Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:68PfAkWhB3aNsu1VKmmABZ Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:3EI9bocQ7EejDzxTUI4xge Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:3EI9bocQ7EejDzxTUI4xge Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:1HzuQ1eeTmyCvkjnc1Mjfk Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:1HzuQ1eeTmyCvkjnc1Mjfk Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:1rTehBaAVJXerJFOG7Bpva Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:1rTehBaAVJXerJFOG7Bpva Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:7mtolkfccQjzWsJ3PDH8nx Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:7mtolkfccQjzWsJ3PDH8nx Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:5p3YfQV3VSpdbfNiQrUupJ Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:5p3YfQV3VSpdbfNiQrUupJ Feb 17 18:56:00 znt volumio[1197]: info: Adding Item to queue: spotify:track:5w66j2edDd5gMFPCNblrjJ Feb 17 18:56:00 znt volumio[1197]: info: Using cached record of: spotify:track:5w66j2edDd5gMFPCNblrjJ Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:00 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::play index undefined Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::getTrack 3 Feb 17 18:56:00 znt volumio[1197]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:56:00 znt volumio[1197]: info: CorePlayQueue::getTrack 3 Feb 17 18:56:00 znt volumio[1197]: info: [1739818560591] ControllerSpotify::clearAddPlayTrack Feb 17 18:56:00 znt volumio[1197]: info: Sending Spotify command with payload to local API: /player/play Feb 17 18:56:01 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:01 znt volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::ClearQueue Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:01 znt volumio[1197]: info: CorePlayQueue::clearPlayQueue Feb 17 18:56:01 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:01 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:01 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:01 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:01 znt volumio[1197]: info: Adding Item to queue: spotify:album:6BKk8WfygFRAEUVnkUji8U Feb 17 18:56:01 znt volumio[1197]: info: Using cached record of: spotify:album:6BKk8WfygFRAEUVnkUji8U Feb 17 18:56:01 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:01 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:01 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:01 znt volumio[1197]: info: CoreCommandRouter::volumioPlay Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::play index 0 Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::play index undefined Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:01 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:01 znt volumio[1197]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:56:01 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:01 znt volumio[1197]: info: [1739818561640] ControllerSpotify::clearAddPlayTrack Feb 17 18:56:01 znt volumio[1197]: info: Sending Spotify command with payload to local API: /player/play Feb 17 18:56:02 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:02 znt volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::ClearQueue Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:02 znt volumio[1197]: info: CorePlayQueue::clearPlayQueue Feb 17 18:56:02 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:02 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:02 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:02 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:02 znt volumio[1197]: info: Adding Item to queue: spotify:album:6BKk8WfygFRAEUVnkUji8U Feb 17 18:56:02 znt volumio[1197]: info: Using cached record of: spotify:album:6BKk8WfygFRAEUVnkUji8U Feb 17 18:56:02 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:02 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:02 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:02 znt volumio[1197]: info: CoreCommandRouter::volumioPlay Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::play index 0 Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::play index undefined Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:02 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:02 znt volumio[1197]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:56:02 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:02 znt volumio[1197]: info: [1739818562141] ControllerSpotify::clearAddPlayTrack Feb 17 18:56:02 znt volumio[1197]: info: Sending Spotify command with payload to local API: /player/play Feb 17 18:56:02 znt go-librespot[1697]: time="2025-02-17T18:56:02Z" level=debug msg="fetched first chunk of 6, total size is 3020466 bytes" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:02 znt go-librespot[1697]: time="2025-02-17T18:56:02Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:02 znt go-librespot[1697]: time="2025-02-17T18:56:02Z" level=info msg="loaded track \"Haltet die Liebe rein\" (paused: false, position: 0ms, duration: 269185ms, prefetched: false)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=trace msg="scheduling prefetch in 239s" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=trace msg="emitting websocket event: metadata" Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","name":"Haltet die Liebe rein","artist_names":["Herzensmelodie"],"album_name":"Du bist mein","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","position":0,"duration":269185,"release_date":"year:2024 month:6 day:22","track_number":1,"disc_number":1}} Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=trace msg="emitting websocket event: playing" Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","play_origin":"go-librespot"}} Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:03 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:03 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="fetched chunk 3/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="resolved context of track" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioAddQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:03 znt volumio[1197]: info: Adding Item to queue: spotify:track:4GyG53nBdaEvSEn9o8roA2 Feb 17 18:56:03 znt volumio[1197]: info: Exploding uri spotify:track:4GyG53nBdaEvSEn9o8roA2 in service spop Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4GyG53nBdaEvSEn9o8roA2 Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioAddQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:03 znt volumio[1197]: info: Adding Item to queue: spotify:track:2ixsaeFioXJmMgkkbd4uj1 Feb 17 18:56:03 znt volumio[1197]: info: Exploding uri spotify:track:2ixsaeFioXJmMgkkbd4uj1 in service spop Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2ixsaeFioXJmMgkkbd4uj1 Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=trace msg="emitting websocket event: will_play" Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","play_origin":"go-librespot"}} Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioAddQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:03 znt volumio[1197]: info: Adding Item to queue: spotify:track:6oHDvarQSp0mf5AD1SyNH0 Feb 17 18:56:03 znt volumio[1197]: info: Exploding uri spotify:track:6oHDvarQSp0mf5AD1SyNH0 in service spop Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6oHDvarQSp0mf5AD1SyNH0 Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioAddQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:03 znt volumio[1197]: info: Adding Item to queue: spotify:track:2yEPMTjt6Jp7yS9zh12ZHb Feb 17 18:56:03 znt volumio[1197]: info: Exploding uri spotify:track:2yEPMTjt6Jp7yS9zh12ZHb in service spop Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2yEPMTjt6Jp7yS9zh12ZHb Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:03 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:03 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:03 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:03 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:03 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:03 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:03 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="fetched chunk 2/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="selected format OGG_VORBIS_96 (1d767c166db72eb178742c46b78467c646480820)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="requested aes key for file 1d767c166db72eb178742c46b78467c646480820, gid: 6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:03 znt go-librespot[1697]: time="2025-02-17T18:56:03Z" level=debug msg="fetched chunk 1/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:03 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioAddQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:03 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:03 znt volumio[1197]: info: Adding Item to queue: spotify:track:55VQvOY6dT63vHmYlWJDpG Feb 17 18:56:03 znt volumio[1197]: info: Exploding uri spotify:track:55VQvOY6dT63vHmYlWJDpG in service spop Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:55VQvOY6dT63vHmYlWJDpG Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6oHDvarQSp0mf5AD1SyNH0","service":"spop","name":"Lieblingsmensch","artist":"Namika","album":"Nador","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b2732e92c9d7c00602608318dca8","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ixsaeFioXJmMgkkbd4uj1","service":"spop","name":"Budapest","artist":"George Ezra","album":"Wanted on Voyage (Expanded Edition)","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b273d6df3bccf3ec41ea2f76debc","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4GyG53nBdaEvSEn9o8roA2","service":"spop","name":"Geiles Leben - Madizin Single Mix","artist":"Glasperlenspiel","album":"Tag X (Geiles Leben Edition)","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b273b3e4cdf7315c4a1696025048","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yEPMTjt6Jp7yS9zh12ZHb","service":"spop","name":"Wenn sie tanzt","artist":"Max Giesinger","album":"Der Junge, der rennt","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2735ac2d9de7d94d7958c2072d1","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:03 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:55VQvOY6dT63vHmYlWJDpG","service":"spop","name":"Ich lass für dich das Licht an","artist":"Revolverheld","album":"Immer in Bewegung","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273b11030b32abcfaa097e41be9","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:03 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:03 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:03 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="fetched first chunk of 6, total size is 3020466 bytes" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:04 znt volumio[1197]: info: All cached search sources collected Feb 17 18:56:04 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 17 18:56:04 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=info msg="loaded track \"Haltet die Liebe rein\" (paused: false, position: 0ms, duration: 269185ms, prefetched: false)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=trace msg="scheduling prefetch in 239s" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=trace msg="emitting websocket event: metadata" Feb 17 18:56:04 znt volumio[1197]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","name":"Haltet die Liebe rein","artist_names":["Herzensmelodie"],"album_name":"Du bist mein","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","position":0,"duration":269185,"release_date":"year:2024 month:6 day:22","track_number":1,"disc_number":1}} Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="fetched chunk 1/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="resolved context of track" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=trace msg="emitting websocket event: will_play" Feb 17 18:56:04 znt volumio[1197]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","play_origin":"go-librespot"}} Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="fetched chunk 2/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="selected format OGG_VORBIS_96 (9bad6f01a807e359178f7b8b60d55d3f56158482)" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="requested aes key for file 9bad6f01a807e359178f7b8b60d55d3f56158482, gid: 5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:04 znt go-librespot[1697]: time="2025-02-17T18:56:04Z" level=debug msg="fetched chunk 3/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:04 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="fetched first chunk of 5, total size is 2497618 bytes" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:05 znt volumio[1197]: info: All search sources collected, pushing search results Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=info msg="loaded track \"Sie kam sehr unverhofft\" (paused: false, position: 0ms, duration: 223380ms, prefetched: false)" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:05 znt volumio[1197]: info: METAVOLUMIO: Infinity Playback added 5 Tracks to queue Feb 17 18:56:05 znt volumiossh-tunnel[32329]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="fetched chunk 3/4, size: 524288" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=trace msg="scheduling prefetch in 193s" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=trace msg="emitting websocket event: metadata" Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","name":"Sie kam sehr unverhofft","artist_names":["Herzensmelodie"],"album_name":"Du bist mein","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","position":0,"duration":223380,"release_date":"year:2024 month:6 day:22","track_number":4,"disc_number":1}} Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=trace msg="emitting websocket event: playing" Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","play_origin":"go-librespot"}} Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:05 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=trace msg="emitting websocket event: playing" Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","play_origin":"go-librespot"}} Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:05 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="fetched chunk 1/4, size: 524288" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="resolved context of track" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="fetched chunk 2/4, size: 524288" uri="spotify:track:5dr4xwJvEmbJ4DFEGPjoL7" Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:05 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=trace msg="emitting websocket event: will_play" Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","play_origin":"go-librespot"}} Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="selected format OGG_VORBIS_96 (1d767c166db72eb178742c46b78467c646480820)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:05 znt go-librespot[1697]: time="2025-02-17T18:56:05Z" level=debug msg="requested aes key for file 1d767c166db72eb178742c46b78467c646480820, gid: 6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Sie kam sehr unverhofft","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:5dr4xwJvEmbJ4DFEGPjoL7","trackType":"spotify","seek":0,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:05 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:05 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:05 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:05 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:05 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:05 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="fetched first chunk of 6, total size is 3020466 bytes" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=info msg="loaded track \"Haltet die Liebe rein\" (paused: false, position: 0ms, duration: 269185ms, prefetched: false)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=trace msg="scheduling prefetch in 239s" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=trace msg="emitting websocket event: metadata" Feb 17 18:56:06 znt volumio[1197]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","name":"Haltet die Liebe rein","artist_names":["Herzensmelodie"],"album_name":"Du bist mein","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","position":0,"duration":269185,"release_date":"year:2024 month:6 day:22","track_number":1,"disc_number":1}} Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="fetched chunk 3/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="fetched chunk 1/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="resolved context of track" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=trace msg="emitting websocket event: will_play" Feb 17 18:56:06 znt volumio[1197]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","play_origin":"go-librespot"}} Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="selected format OGG_VORBIS_96 (1d767c166db72eb178742c46b78467c646480820)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="requested aes key for file 1d767c166db72eb178742c46b78467c646480820, gid: 6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:06 znt go-librespot[1697]: time="2025-02-17T18:56:06Z" level=debug msg="fetched chunk 2/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=debug msg="fetched first chunk of 6, total size is 3020466 bytes" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=info msg="loaded track \"Haltet die Liebe rein\" (paused: false, position: 0ms, duration: 269185ms, prefetched: false)" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=trace msg="scheduling prefetch in 239s" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=trace msg="emitting websocket event: metadata" Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","name":"Haltet die Liebe rein","artist_names":["Herzensmelodie"],"album_name":"Du bist mein","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","position":0,"duration":269185,"release_date":"year:2024 month:6 day:22","track_number":1,"disc_number":1}} Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=trace msg="emitting websocket event: playing" Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","play_origin":"go-librespot"}} Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:08 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=trace msg="emitting websocket event: playing" Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","play_origin":"go-librespot"}} Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:08 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:08 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":0,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:08 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:08 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:08 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:08 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:08 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:08 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=debug msg="fetched chunk 1/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:08 znt go-librespot[1697]: time="2025-02-17T18:56:08Z" level=debug msg="fetched chunk 3/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:09 znt go-librespot[1697]: time="2025-02-17T18:56:09Z" level=debug msg="fetched chunk 2/5, size: 524288" uri="spotify:track:6ZHaVH17yrzdIH6y7mQCcG" Feb 17 18:56:20 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Feb 17 18:56:20 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1j2M5ekufbxpzGYzuorgKt Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5bC8KUCCC42zzBUVyV2wkf Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:39MpYAVgvY5QAyliJolKLr Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:72jQnB6Zv4rXvJfJFAD8Vl Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4Nd5HJn4EExnLmHtClk4QV Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:39HXEAo3daWxi03Kh9CNss Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:394tuKs2BrLp3LWpZ4vPcA Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0zTtZNQMYLIIPY0435pWke Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6868p4EacoPiAV0m8FLdw4 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3j3AeItJcyMsW6OEgdbbOn Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2ySC8NX5kMgFQK9d4P2oBZ Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4KV9bM7a1KDc7b7OakFZic Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3q8tJKCvgzBAjQDo088EwQ Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6z7EhLacKW31hwi8VZy7eg Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6GEcHAvAimdYBQZf8TuP7y Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3zxfFlaDm7PXaKRvznc630 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5BeN2CbOlFB6rPO404SL2t Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0U5vNk030Ok7Zg2GIuRv31 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:24KiFKXh53GSFCo1oEexa5 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1V8fIhUjgyJmui9qBEhaGr Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1TUw68gSe2WwP0EzHBXXYt Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3cRn6HcfwqTaI3PK66WNjL Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2uKMUMsvAbG1n1prguI9n6 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4DnxlEebyu9Wq0qqbHX7xV Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4jXUBtUfXYlD2ojnuUXf0i Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:7GurGTDwFerxlGWJKQhb12 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:7DGKbfO4PgI8DVfY4dEPdv Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2t1Sg4ZZixhpEU0OvEStHh Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6pwxZXpLoyVLr0Nj9I7A4E Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6TRW3XCnG6aWwYqL2SVbJp Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4RRErNxg0apQJwv3KpYNlO Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:7Dl8KLhV9xHGQheaXp8zlm Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6bJ8bj8olLWzeEAFfVKuLm Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0c7NIS2Yvvg2kk3Nj8Ujmi Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3mFiw8ED9ocw29Eni6ftcV Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:48XoUqz1ppGYs3LcIcmBgv Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0VWf8vbdQcu2jBMl2YZDRK Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6NCinkjT1jl01ztbyA06o7 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:7JxlzZbThFMVrmRNj5gMTd Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5zpIrTni0FvbPZm1Hrgj02 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:24S7sXZG8BqrKbC5NV6vRE Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:71xA9ohP48l73M8SjeBvAw Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0srxFzFG1iZUYLh0Cer6vL Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:7z52m2AzmG2hfgD39nqz6u Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6DroKp4Ae42iBzMeTMOJDs Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:33rmrki0o4aZreYHH2rosC Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:7ueHFhN0MVlXoX3TTTIhW6 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2It9Xd3T4evzCdioI9HMca Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0iLyAlInsomkzvxAMYlS6R Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:35kQ9ZpF377PSJs8Bp9gtn Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0iDHfRNAxWdEJSUZ23l9iF Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3xsquiGnSz4gUwUe21EgEP Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6v5ALI4h54cRACSoTsD8Ys Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6VFNxSkyGq1RSQPvOX7TfK Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1EBBJwqxdqOkUADZphvxJQ Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:79YMuijyvRl7Fqs6A7XMW1 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0JUI5FFS7Qg4SLNnhgJzPM Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:38uCfMImzpz4GSdAobaYnA Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:774zYqMSKwjCJ4DqqEwcyJ Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1jatO9tjEDGnftxxC4QrAm Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2hUGy5wm3mq2kNvobv5RPr Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6j3oB7ZcCIturLnVWpwHeo Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4xL692QnfjwvYArp1f2vzW Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2JQLX2If5jduYeAKZEVvFb Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5DD0zeG1koiTrIcEddGVdZ Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3yM4SF4fhnGZe3MEPTlnIB Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6kdQCjtktoBnTfEi840jEH Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2DNQOA0b60A7A7oHxCdiZt Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4EUyxXAy1iBilEV5xR7bqc Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1FiT2RqybwzHD7InEBlkLI Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1vwUYRoCcJUlEEUBJ3hAYo Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3wECJLFkS6cGvdyVOmGFme Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3w7rXn3KkAOzzEpFFIBv0m Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0Ocn3xgc11po4OsvopiLt1 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5VtDMwO8FjhRmPn94YqHVt Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1gRCVbHetueksROCSugRp5 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:34RlKO9Dt7sd0Hik52Xinx Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3h6G8LEQKNNzQjVgbuazuy Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4nZa6DzsJwR9Lf9AdMuc6a Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2A9PhZxveFpZxWU5Pf9UMI Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:7Bk0VK59GsrYX6CwfbDsQ9 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5BRQUbTNg40P9X4Ckw8nIn Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6FGR7ykXl8buAyT0kMfdB9 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:1LiW4oFjovnHw6bajYmR3H Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5KuuAcWRtfEkVgWtm7o2th Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:5h8Drc5We5CdOITzuFHx17 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:4jmrg48Cp8EDmOLZpkXDw9 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:2WtsALda64u2bW5jpiMRhD Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:3XWelzCZPM8J5Swsgp8eJO Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0MWlqwA1YUpM00tVxD2gk1 Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:609cexFubQ8JQ5u20Jbbkx Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:6hvo5KPU0Pk1Lc5vo6iVQB Feb 17 18:56:20 znt volumio[1197]: info: Preloading song: spotify:track:0KMiWpGnbzjs4eSGBvVr3o Feb 17 18:56:20 znt volumio[1197]: info: Exploding uri spotify:track:1j2M5ekufbxpzGYzuorgKt in service spop Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1j2M5ekufbxpzGYzuorgKt Feb 17 18:56:20 znt volumio[1197]: info: Exploding uri spotify:track:5bC8KUCCC42zzBUVyV2wkf in service spop Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5bC8KUCCC42zzBUVyV2wkf Feb 17 18:56:20 znt volumio[1197]: info: Exploding uri spotify:track:39MpYAVgvY5QAyliJolKLr in service spop Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:39MpYAVgvY5QAyliJolKLr Feb 17 18:56:20 znt volumio[1197]: info: Exploding uri spotify:track:72jQnB6Zv4rXvJfJFAD8Vl in service spop Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:72jQnB6Zv4rXvJfJFAD8Vl Feb 17 18:56:20 znt volumio[1197]: info: Exploding uri spotify:track:4Nd5HJn4EExnLmHtClk4QV in service spop Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4Nd5HJn4EExnLmHtClk4QV Feb 17 18:56:20 znt volumio[1197]: info: Exploding uri spotify:track:39HXEAo3daWxi03Kh9CNss in service spop Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:39HXEAo3daWxi03Kh9CNss Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5bC8KUCCC42zzBUVyV2wkf","service":"spop","name":"Prelude in C Major","artist":"Johann Sebastian Bach","album":"Forest Aura Yoga","type":"song","duration":161,"albumart":"https://i.scdn.co/image/ab67616d0000b273b5f75c16a8c8442b1a990efb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1j2M5ekufbxpzGYzuorgKt","service":"spop","name":"Moonlight Sonata","artist":"Ludwig van Beethoven","album":"Moonlight Sonata - Single","type":"song","duration":447,"albumart":"https://i.scdn.co/image/ab67616d0000b27339403a909cadae42157cc6f6","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:20 znt volumio[1197]: info: Exploding uri spotify:track:394tuKs2BrLp3LWpZ4vPcA in service spop Feb 17 18:56:20 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:394tuKs2BrLp3LWpZ4vPcA Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:0zTtZNQMYLIIPY0435pWke in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0zTtZNQMYLIIPY0435pWke Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:39MpYAVgvY5QAyliJolKLr","service":"spop","name":"Air on the G String","artist":"Johann Sebastian Bach","album":"New Dawn","type":"song","duration":225,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6eafda95e71e1250a1e3718","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:72jQnB6Zv4rXvJfJFAD8Vl","service":"spop","name":"Canon in D Major","artist":"Johann Pachelbel","album":"100 Most Essential Classical Favorites","type":"song","duration":376,"albumart":"https://i.scdn.co/image/ab67616d0000b27390a71e369ff35cf594b80ef3","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:6868p4EacoPiAV0m8FLdw4 in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6868p4EacoPiAV0m8FLdw4 Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:3j3AeItJcyMsW6OEgdbbOn in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3j3AeItJcyMsW6OEgdbbOn Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:2ySC8NX5kMgFQK9d4P2oBZ in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2ySC8NX5kMgFQK9d4P2oBZ Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:4KV9bM7a1KDc7b7OakFZic in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4KV9bM7a1KDc7b7OakFZic Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:3q8tJKCvgzBAjQDo088EwQ in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3q8tJKCvgzBAjQDo088EwQ Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:6z7EhLacKW31hwi8VZy7eg in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6z7EhLacKW31hwi8VZy7eg Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:6GEcHAvAimdYBQZf8TuP7y in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6GEcHAvAimdYBQZf8TuP7y Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:3zxfFlaDm7PXaKRvznc630 in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3zxfFlaDm7PXaKRvznc630 Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:5BeN2CbOlFB6rPO404SL2t in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5BeN2CbOlFB6rPO404SL2t Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:0U5vNk030Ok7Zg2GIuRv31 in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0U5vNk030Ok7Zg2GIuRv31 Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:39HXEAo3daWxi03Kh9CNss","service":"spop","name":"Am Schöpfungsanfang","artist":"Herzensmelodie","album":"Das Gebet der Eltern","type":"song","duration":267,"albumart":"https://i.scdn.co/image/ab67616d0000b27346d7ef2a33126a37e51bf80e","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Nd5HJn4EExnLmHtClk4QV","service":"spop","name":"Ode to Joy","artist":"Ludwig van Beethoven","album":"Classical Best Of","type":"song","duration":656,"albumart":"https://i.scdn.co/image/ab67616d0000b273570c46d1a3c0b590c7dfbb88","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:394tuKs2BrLp3LWpZ4vPcA","service":"spop","name":"Das Gebet der Eltern","artist":"Herzensmelodie","album":"Das Gebet der Eltern","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b27346d7ef2a33126a37e51bf80e","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:24KiFKXh53GSFCo1oEexa5 in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:24KiFKXh53GSFCo1oEexa5 Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:1V8fIhUjgyJmui9qBEhaGr in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1V8fIhUjgyJmui9qBEhaGr Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:1TUw68gSe2WwP0EzHBXXYt in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1TUw68gSe2WwP0EzHBXXYt Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:3cRn6HcfwqTaI3PK66WNjL in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3cRn6HcfwqTaI3PK66WNjL Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3j3AeItJcyMsW6OEgdbbOn","service":"spop","name":"Dort litt der Herr","artist":"Herzensmelodie","album":"Dort litt der Herr","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b2731d58090576a52b58367d429f","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:2uKMUMsvAbG1n1prguI9n6 in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2uKMUMsvAbG1n1prguI9n6 Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0zTtZNQMYLIIPY0435pWke","service":"spop","name":"Ich will ins Himmelreich","artist":"Herzensmelodie","album":"Ich will ins Himmelreich","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b2730c7fb1ed9c84b46819e95b8b","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:4DnxlEebyu9Wq0qqbHX7xV in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4DnxlEebyu9Wq0qqbHX7xV Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ySC8NX5kMgFQK9d4P2oBZ","service":"spop","name":"Jesus, der Herr, will mich brauchen","artist":"Herzensmelodie","album":"Kleine Kinder","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273720ab2ee29a1c8a93d62bf1b","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:24KiFKXh53GSFCo1oEexa5","service":"spop","name":"35 - Die Schoko Erpressung - Teil 15","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":295,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3q8tJKCvgzBAjQDo088EwQ","service":"spop","name":"Frosty The Snowman","artist":"Jimmy Durante","album":"A Holly Jolly Kids Christmas","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273091da12e38082f0c86d52551","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:4jXUBtUfXYlD2ojnuUXf0i in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4jXUBtUfXYlD2ojnuUXf0i Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6868p4EacoPiAV0m8FLdw4","service":"spop","name":"Zuhause","artist":"Max Giesinger","album":"Die Reise","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b273ab617a8fd62368993bf8924f","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6z7EhLacKW31hwi8VZy7eg","service":"spop","name":"Kleine Kinder","artist":"Herzensmelodie","album":"Kleine Kinder","type":"song","duration":140,"albumart":"https://i.scdn.co/image/ab67616d0000b273720ab2ee29a1c8a93d62bf1b","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4KV9bM7a1KDc7b7OakFZic","service":"spop","name":"Jingle Bells","artist":"Frank Sinatra","album":"Ultimate Christmas","type":"song","duration":122,"albumart":"https://i.scdn.co/image/ab67616d0000b273292a3767391b5767f95e708f","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3zxfFlaDm7PXaKRvznc630","service":"spop","name":"Krippe & Kreuz","artist":"Herzensmelodie","album":"Ясли & Крест","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b273c39710419d1ba5d78a5c2655","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6GEcHAvAimdYBQZf8TuP7y","service":"spop","name":"Ясли & Крест","artist":"Herzensmelodie","album":"Ясли & Крест","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b273c39710419d1ba5d78a5c2655","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BeN2CbOlFB6rPO404SL2t","service":"spop","name":"А я хочу приносить плоды","artist":"Herzensmelodie","album":"А я хочу приносить плоды","type":"song","duration":304,"albumart":"https://i.scdn.co/image/ab67616d0000b273245636e65826d62c63b315ee","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:7GurGTDwFerxlGWJKQhb12 in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:7GurGTDwFerxlGWJKQhb12 Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1V8fIhUjgyJmui9qBEhaGr","service":"spop","name":"35 - Die Schoko Erpressung - Teil 14","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:21 znt volumio[1197]: info: Exploding uri spotify:track:7DGKbfO4PgI8DVfY4dEPdv in service spop Feb 17 18:56:21 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:7DGKbfO4PgI8DVfY4dEPdv Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:2t1Sg4ZZixhpEU0OvEStHh in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2t1Sg4ZZixhpEU0OvEStHh Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0U5vNk030Ok7Zg2GIuRv31","service":"spop","name":"Als ich zu Dir kam","artist":"Herzensmelodie","album":"Jesus ist bei mir","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273f85e2ab49898273700ee2d4a","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:6pwxZXpLoyVLr0Nj9I7A4E in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6pwxZXpLoyVLr0Nj9I7A4E Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3cRn6HcfwqTaI3PK66WNjL","service":"spop","name":"35 - Die Schoko Erpressung - Teil 12","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2uKMUMsvAbG1n1prguI9n6","service":"spop","name":"35 - Die Schoko Erpressung - Teil 11","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:6TRW3XCnG6aWwYqL2SVbJp in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6TRW3XCnG6aWwYqL2SVbJp Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4jXUBtUfXYlD2ojnuUXf0i","service":"spop","name":"35 - Die Schoko Erpressung - Teil 09","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4DnxlEebyu9Wq0qqbHX7xV","service":"spop","name":"35 - Die Schoko Erpressung - Teil 10","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:4RRErNxg0apQJwv3KpYNlO in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4RRErNxg0apQJwv3KpYNlO Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7GurGTDwFerxlGWJKQhb12","service":"spop","name":"35 - Die Schoko Erpressung - Teil 08","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:7Dl8KLhV9xHGQheaXp8zlm in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:7Dl8KLhV9xHGQheaXp8zlm Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1TUw68gSe2WwP0EzHBXXYt","service":"spop","name":"35 - Die Schoko Erpressung - Teil 13","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7DGKbfO4PgI8DVfY4dEPdv","service":"spop","name":"35 - Die Schoko Erpressung - Teil 07","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:6bJ8bj8olLWzeEAFfVKuLm in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6bJ8bj8olLWzeEAFfVKuLm Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:0c7NIS2Yvvg2kk3Nj8Ujmi in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0c7NIS2Yvvg2kk3Nj8Ujmi Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:3mFiw8ED9ocw29Eni6ftcV in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3mFiw8ED9ocw29Eni6ftcV Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6pwxZXpLoyVLr0Nj9I7A4E","service":"spop","name":"35 - Die Schoko Erpressung - Teil 05","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2t1Sg4ZZixhpEU0OvEStHh","service":"spop","name":"35 - Die Schoko Erpressung - Teil 06","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:48XoUqz1ppGYs3LcIcmBgv in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:48XoUqz1ppGYs3LcIcmBgv Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Dl8KLhV9xHGQheaXp8zlm","service":"spop","name":"35 - Die Schoko Erpressung - Teil 02","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:0VWf8vbdQcu2jBMl2YZDRK in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0VWf8vbdQcu2jBMl2YZDRK Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4RRErNxg0apQJwv3KpYNlO","service":"spop","name":"35 - Die Schoko Erpressung - Teil 03","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:6NCinkjT1jl01ztbyA06o7 in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6NCinkjT1jl01ztbyA06o7 Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:7JxlzZbThFMVrmRNj5gMTd in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:7JxlzZbThFMVrmRNj5gMTd Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3mFiw8ED9ocw29Eni6ftcV","service":"spop","name":"35 - Die Schoko Erpressung - Inhaltsangabe","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":35,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6bJ8bj8olLWzeEAFfVKuLm","service":"spop","name":"35 - Die Schoko Erpressung - Teil 01","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6TRW3XCnG6aWwYqL2SVbJp","service":"spop","name":"35 - Die Schoko Erpressung - Teil 04","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:5zpIrTni0FvbPZm1Hrgj02 in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5zpIrTni0FvbPZm1Hrgj02 Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0c7NIS2Yvvg2kk3Nj8Ujmi","service":"spop","name":"TKKG Junior Titelsong","artist":"TKKG Junior","album":"Folge 35: Die Schoko Erpressung","type":"song","duration":64,"albumart":"https://i.scdn.co/image/ab67616d0000b273677ee265be17a672080c70da","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:24S7sXZG8BqrKbC5NV6vRE in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:24S7sXZG8BqrKbC5NV6vRE Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:48XoUqz1ppGYs3LcIcmBgv","service":"spop","name":"002 - Beware of the Beast - Part 01","artist":"TKKG - Junior Investigators","album":"002/Beware of the Beast","type":"song","duration":79,"albumart":"https://i.scdn.co/image/ab67616d0000b273e66e6b07fb5a373205d5698e","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:71xA9ohP48l73M8SjeBvAw in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:71xA9ohP48l73M8SjeBvAw Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:0srxFzFG1iZUYLh0Cer6vL in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0srxFzFG1iZUYLh0Cer6vL Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VWf8vbdQcu2jBMl2YZDRK","service":"spop","name":"002 - Beware of the Beast - Intro","artist":"TKKG - Junior Investigators","album":"002/Beware of the Beast","type":"song","duration":20,"albumart":"https://i.scdn.co/image/ab67616d0000b273e66e6b07fb5a373205d5698e","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6NCinkjT1jl01ztbyA06o7","service":"spop","name":"Der Engeltanz","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7JxlzZbThFMVrmRNj5gMTd","service":"spop","name":"Unser Hund macht immer wuff, wuff, wuff","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":136,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:7z52m2AzmG2hfgD39nqz6u in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:7z52m2AzmG2hfgD39nqz6u Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:6DroKp4Ae42iBzMeTMOJDs in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6DroKp4Ae42iBzMeTMOJDs Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:24S7sXZG8BqrKbC5NV6vRE","service":"spop","name":"Disco-King","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":159,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5zpIrTni0FvbPZm1Hrgj02","service":"spop","name":"Ein Mann ging einmal im Wald spazieren","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:33rmrki0o4aZreYHH2rosC in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:33rmrki0o4aZreYHH2rosC Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0srxFzFG1iZUYLh0Cer6vL","service":"spop","name":"Durch die Straßen auf und nieder","artist":"Simone Sommerland","album":"Die 30 besten St. Martins- und Laternenlieder","type":"song","duration":77,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfc0295bc77a2e4d7d626935","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:22 znt volumio[1197]: info: Exploding uri spotify:track:7ueHFhN0MVlXoX3TTTIhW6 in service spop Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:7ueHFhN0MVlXoX3TTTIhW6 Feb 17 18:56:22 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:71xA9ohP48l73M8SjeBvAw","service":"spop","name":"Die coolste Kuh der Welt","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":127,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:2It9Xd3T4evzCdioI9HMca in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2It9Xd3T4evzCdioI9HMca Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7z52m2AzmG2hfgD39nqz6u","service":"spop","name":"Kommt, wir wollen Laterne laufen","artist":"Simone Sommerland","album":"Die 30 besten St. Martins- und Laternenlieder","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfc0295bc77a2e4d7d626935","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6DroKp4Ae42iBzMeTMOJDs","service":"spop","name":"Sankt Martin ritt durch Schnee und Wind","artist":"Simone Sommerland","album":"Die 30 besten St. Martins- und Laternenlieder","type":"song","duration":148,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfc0295bc77a2e4d7d626935","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:0iLyAlInsomkzvxAMYlS6R in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0iLyAlInsomkzvxAMYlS6R Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:33rmrki0o4aZreYHH2rosC","service":"spop","name":"Ich geh mit meiner Laterne","artist":"Simone Sommerland","album":"Die 30 besten St. Martins- und Laternenlieder","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfc0295bc77a2e4d7d626935","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:35kQ9ZpF377PSJs8Bp9gtn in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:35kQ9ZpF377PSJs8Bp9gtn Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ueHFhN0MVlXoX3TTTIhW6","service":"spop","name":"Laterne, Laterne","artist":"Simone Sommerland","album":"Die 30 besten St. Martins- und Laternenlieder","type":"song","duration":106,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfc0295bc77a2e4d7d626935","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:0iDHfRNAxWdEJSUZ23l9iF in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0iDHfRNAxWdEJSUZ23l9iF Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:3xsquiGnSz4gUwUe21EgEP in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3xsquiGnSz4gUwUe21EgEP Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2It9Xd3T4evzCdioI9HMca","service":"spop","name":"Die Kinderpartyeisenbahn - Karaoke Version","artist":"Lichterkinder","album":"Spiel- und Bewegungslieder auf Weltreise","type":"song","duration":120,"albumart":"https://i.scdn.co/image/ab67616d0000b273761ee00220f48336a35d6b1c","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:6v5ALI4h54cRACSoTsD8Ys in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6v5ALI4h54cRACSoTsD8Ys Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:6VFNxSkyGq1RSQPvOX7TfK in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6VFNxSkyGq1RSQPvOX7TfK Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0iLyAlInsomkzvxAMYlS6R","service":"spop","name":"Lichterkinder","artist":"Lichterkinder","album":"Laternen- und Herbstlieder","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b273f48f07d5741163fbff3953d6","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:1EBBJwqxdqOkUADZphvxJQ in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1EBBJwqxdqOkUADZphvxJQ Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:35kQ9ZpF377PSJs8Bp9gtn","service":"spop","name":"小雞嗶嗶 - Radio Edit","artist":"Pulcino Pio","album":"小雞嗶嗶","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b27349e003963618888e3f495055","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0iDHfRNAxWdEJSUZ23l9iF","service":"spop","name":"Wallkürenritt","artist":"Orchestra Modern Times","album":"Die 100 Beliebtesten Melodien Der Klassik Fur Kinder","type":"song","duration":309,"albumart":"https://i.scdn.co/image/ab67616d0000b273eefbf40d53f344828cab041e","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:79YMuijyvRl7Fqs6A7XMW1 in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:79YMuijyvRl7Fqs6A7XMW1 Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:0JUI5FFS7Qg4SLNnhgJzPM in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0JUI5FFS7Qg4SLNnhgJzPM Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6v5ALI4h54cRACSoTsD8Ys","service":"spop","name":"Happy Birthday","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3xsquiGnSz4gUwUe21EgEP","service":"spop","name":"Wir müssen jetzt gehen","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":130,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:38uCfMImzpz4GSdAobaYnA in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:38uCfMImzpz4GSdAobaYnA Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:774zYqMSKwjCJ4DqqEwcyJ in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:774zYqMSKwjCJ4DqqEwcyJ Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6VFNxSkyGq1RSQPvOX7TfK","service":"spop","name":"Kleines Häschen","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:1jatO9tjEDGnftxxC4QrAm in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1jatO9tjEDGnftxxC4QrAm Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1EBBJwqxdqOkUADZphvxJQ","service":"spop","name":"Wer hat Angst vorm Tiger","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":118,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:2hUGy5wm3mq2kNvobv5RPr in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2hUGy5wm3mq2kNvobv5RPr Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:6j3oB7ZcCIturLnVWpwHeo in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6j3oB7ZcCIturLnVWpwHeo Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:4xL692QnfjwvYArp1f2vzW in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4xL692QnfjwvYArp1f2vzW Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:38uCfMImzpz4GSdAobaYnA","service":"spop","name":"In der Weihnachtsbäckerei","artist":"Simone Sommerland","album":"Die 100 besten Weihnachtslieder für Kinder","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b27329e2f54e8bae3e89d689c7d4","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:2JQLX2If5jduYeAKZEVvFb in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2JQLX2If5jduYeAKZEVvFb Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0JUI5FFS7Qg4SLNnhgJzPM","service":"spop","name":"Jingle Bells","artist":"Simone Sommerland","album":"Die 100 besten Weihnachtslieder für Kinder","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b27329e2f54e8bae3e89d689c7d4","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:79YMuijyvRl7Fqs6A7XMW1","service":"spop","name":"1-2-3-4-5-6-7","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2hUGy5wm3mq2kNvobv5RPr","service":"spop","name":"Meine Oma fährt im Hühnerstall Motorrad - Neue Textversion","artist":"Simone Sommerland","album":"Die 30 besten alten Kinderlieder mit zeitgemäßen Texten","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b273ab1f12bd1e96c16c472c494c","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:5DD0zeG1koiTrIcEddGVdZ in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5DD0zeG1koiTrIcEddGVdZ Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1jatO9tjEDGnftxxC4QrAm","service":"spop","name":"Hab 'ne Tante aus Marokko - Neue Textversion","artist":"Simone Sommerland","album":"Die 30 besten alten Kinderlieder mit zeitgemäßen Texten","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b273ab1f12bd1e96c16c472c494c","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:774zYqMSKwjCJ4DqqEwcyJ","service":"spop","name":"Drei Schimpansen mit 'ner Ananas - Neue Textversion von \"Drei Chinesen mit dem Kontrabass\"","artist":"Simone Sommerland","album":"Die 30 besten alten Kinderlieder mit zeitgemäßen Texten","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b273ab1f12bd1e96c16c472c494c","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:3yM4SF4fhnGZe3MEPTlnIB in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3yM4SF4fhnGZe3MEPTlnIB Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6j3oB7ZcCIturLnVWpwHeo","service":"spop","name":"Ein Sturm zieht auf","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:23 znt volumio[1197]: info: Exploding uri spotify:track:6kdQCjtktoBnTfEi840jEH in service spop Feb 17 18:56:23 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6kdQCjtktoBnTfEi840jEH Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:2DNQOA0b60A7A7oHxCdiZt in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2DNQOA0b60A7A7oHxCdiZt Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4xL692QnfjwvYArp1f2vzW","service":"spop","name":"Weil wir noch Kinder sind","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":140,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2JQLX2If5jduYeAKZEVvFb","service":"spop","name":"Ich bin ein Tiger","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":136,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:4EUyxXAy1iBilEV5xR7bqc in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4EUyxXAy1iBilEV5xR7bqc Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3yM4SF4fhnGZe3MEPTlnIB","service":"spop","name":"Heute spukt es im Haus","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DD0zeG1koiTrIcEddGVdZ","service":"spop","name":"Der Affe tanzt Cha-Cha-Cha","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":128,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:1FiT2RqybwzHD7InEBlkLI in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1FiT2RqybwzHD7InEBlkLI Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6kdQCjtktoBnTfEi840jEH","service":"spop","name":"Morgens macht der Elefant","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:1vwUYRoCcJUlEEUBJ3hAYo in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1vwUYRoCcJUlEEUBJ3hAYo Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2DNQOA0b60A7A7oHxCdiZt","service":"spop","name":"Das Huhn und der Gummibaum","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:3wECJLFkS6cGvdyVOmGFme in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3wECJLFkS6cGvdyVOmGFme Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4EUyxXAy1iBilEV5xR7bqc","service":"spop","name":"Eins und zwei, Polizei","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":108,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:3w7rXn3KkAOzzEpFFIBv0m in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3w7rXn3KkAOzzEpFFIBv0m Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:0Ocn3xgc11po4OsvopiLt1 in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0Ocn3xgc11po4OsvopiLt1 Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1FiT2RqybwzHD7InEBlkLI","service":"spop","name":"Wir lieben Ostern","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:5VtDMwO8FjhRmPn94YqHVt in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5VtDMwO8FjhRmPn94YqHVt Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1vwUYRoCcJUlEEUBJ3hAYo","service":"spop","name":"Das Fröschlein saß auf einem Blatt","artist":"Simone Sommerland","album":"Die 30 besten Kinderlieder 2024","type":"song","duration":101,"albumart":"https://i.scdn.co/image/ab67616d0000b2738a2edffe7a8c39632afce028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:1gRCVbHetueksROCSugRp5 in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1gRCVbHetueksROCSugRp5 Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:34RlKO9Dt7sd0Hik52Xinx in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:34RlKO9Dt7sd0Hik52Xinx Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3w7rXn3KkAOzzEpFFIBv0m","service":"spop","name":"Sankt Martin","artist":"Simone Sommerland","album":"Die 30 besten St. Martins- und Laternenlieder","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfc0295bc77a2e4d7d626935","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:3h6G8LEQKNNzQjVgbuazuy in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3h6G8LEQKNNzQjVgbuazuy Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Ocn3xgc11po4OsvopiLt1","service":"spop","name":"Leo Lausemaus Titelsong","artist":"Leo Lausemaus","album":"Folge 20","type":"song","duration":79,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6c80c23b154607104ef744c","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3wECJLFkS6cGvdyVOmGFme","service":"spop","name":"Der Körperteil Blues","artist":"Lichterkinder","album":"Spiel - und Bewegungslieder","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273344e67cc3b14d91556d63597","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:4nZa6DzsJwR9Lf9AdMuc6a in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4nZa6DzsJwR9Lf9AdMuc6a Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5VtDMwO8FjhRmPn94YqHVt","service":"spop","name":"1. Dezember - Lotta und Luis und der Fall X-Mes - Lotta und Luis und der Fall X-Mes","artist":"Bibellesebund Verlag","album":"Lotta und Luis und der Fall X-Mes (Adventskalender-Geschichte)","type":"song","duration":419,"albumart":"https://i.scdn.co/image/ab67616d0000b273d658e399c3148c6b15c9a1c2","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:2A9PhZxveFpZxWU5Pf9UMI in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2A9PhZxveFpZxWU5Pf9UMI Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1gRCVbHetueksROCSugRp5","service":"spop","name":"Die drei kleinen Schweinchen - Teil 02","artist":"SimsalaGrimm","album":"Die drei kleinen Schweinchen (Das Original-Hörspiel zur TV Serie)","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273341d1c8e6c1e8290cfc73028","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:7Bk0VK59GsrYX6CwfbDsQ9 in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:7Bk0VK59GsrYX6CwfbDsQ9 Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:34RlKO9Dt7sd0Hik52Xinx","service":"spop","name":"Och Gary, nee","artist":"SpongeBob Schwammkopf","album":"Schwammtastisch","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273d35e80aa956b29deec1c6704","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:5BRQUbTNg40P9X4Ckw8nIn in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5BRQUbTNg40P9X4Ckw8nIn Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4nZa6DzsJwR9Lf9AdMuc6a","service":"spop","name":"Intro (geht schwammtastisch los)","artist":"SpongeBob Schwammkopf","album":"Schwammtastisch","type":"song","duration":97,"albumart":"https://i.scdn.co/image/ab67616d0000b273d35e80aa956b29deec1c6704","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:6FGR7ykXl8buAyT0kMfdB9 in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6FGR7ykXl8buAyT0kMfdB9 Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3h6G8LEQKNNzQjVgbuazuy","service":"spop","name":"09 - Lotta und Luis und der Schreck vor Ostern","artist":"Bibellesebund Verlag","album":"Lotta und Luis und der Schreck vor Ostern","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b273f594bccb1accbc2002caa388","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:1LiW4oFjovnHw6bajYmR3H in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:1LiW4oFjovnHw6bajYmR3H Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2A9PhZxveFpZxWU5Pf9UMI","service":"spop","name":"Prizeman, Beethoven: Joyful, Joyful, We adore Thee (After Beethoven's Ode to Joy from Symphony No. 9 in D Minor, Op. 125) [Live]","artist":"Ludwig van Beethoven","album":"Angels Sing - Libera in America","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc78bc8cd66a2302c764a198","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Bk0VK59GsrYX6CwfbDsQ9","service":"spop","name":"Libertango","artist":"Astor Piazzolla","album":"Hora Cero","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b273322ff8034c34315c22bf1bb7","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:5KuuAcWRtfEkVgWtm7o2th in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5KuuAcWRtfEkVgWtm7o2th Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:5h8Drc5We5CdOITzuFHx17 in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:5h8Drc5We5CdOITzuFHx17 Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BRQUbTNg40P9X4Ckw8nIn","service":"spop","name":"Abenteuerspaß mit Conni (Intro)","artist":"Conni","album":"Conni im Ferienlager (Abenteuerspaß mit Conni)","type":"song","duration":65,"albumart":"https://i.scdn.co/image/ab67616d0000b27355650eef4955b0c4c3346043","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:24 znt volumio[1197]: info: Exploding uri spotify:track:4jmrg48Cp8EDmOLZpkXDw9 in service spop Feb 17 18:56:24 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:4jmrg48Cp8EDmOLZpkXDw9 Feb 17 18:56:25 znt volumio[1197]: info: Exploding uri spotify:track:2WtsALda64u2bW5jpiMRhD in service spop Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:2WtsALda64u2bW5jpiMRhD Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1LiW4oFjovnHw6bajYmR3H","service":"spop","name":"Carnival of the Animals, R. 125: VII. Aquarium","artist":"Camille Saint-Saëns","album":"Saint-Saëns","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: info: Exploding uri spotify:track:3XWelzCZPM8J5Swsgp8eJO in service spop Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:3XWelzCZPM8J5Swsgp8eJO Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6FGR7ykXl8buAyT0kMfdB9","service":"spop","name":"30 Etudes, Op. 26: No. 10 in F-Sharp Minor. Adagio","artist":"Louise Farrenc","album":"Saint-Saëns","type":"song","duration":328,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5KuuAcWRtfEkVgWtm7o2th","service":"spop","name":"Carnival of the Animals, R. 125: VIII. Personages with Long Ears","artist":"Camille Saint-Saëns","album":"Saint-Saëns","type":"song","duration":43,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: info: Exploding uri spotify:track:0MWlqwA1YUpM00tVxD2gk1 in service spop Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0MWlqwA1YUpM00tVxD2gk1 Feb 17 18:56:25 znt volumio[1197]: info: Exploding uri spotify:track:609cexFubQ8JQ5u20Jbbkx in service spop Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:609cexFubQ8JQ5u20Jbbkx Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5h8Drc5We5CdOITzuFHx17","service":"spop","name":"Carnival of the Animals, R. 125: III. Wild Asses","artist":"Camille Saint-Saëns","album":"Saint-Saëns","type":"song","duration":40,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4jmrg48Cp8EDmOLZpkXDw9","service":"spop","name":"Carnival of the Animals, R. 125: II. Hens and Cocks","artist":"Camille Saint-Saëns","album":"Saint-Saëns","type":"song","duration":45,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: info: Exploding uri spotify:track:6hvo5KPU0Pk1Lc5vo6iVQB in service spop Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:6hvo5KPU0Pk1Lc5vo6iVQB Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2WtsALda64u2bW5jpiMRhD","service":"spop","name":"Carnival of the Animals, R. 125: Ib. Royal March of the Lion","artist":"Camille Saint-Saëns","album":"Saint-Saëns","type":"song","duration":95,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: info: Exploding uri spotify:track:0KMiWpGnbzjs4eSGBvVr3o in service spop Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: EXPLODING URI:spotify:track:0KMiWpGnbzjs4eSGBvVr3o Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3XWelzCZPM8J5Swsgp8eJO","service":"spop","name":"Carnival of the Animals, R. 125: Ia. Introduction","artist":"Camille Saint-Saëns","album":"Saint-Saëns","type":"song","duration":30,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0MWlqwA1YUpM00tVxD2gk1","service":"spop","name":"Carnival of the Animals, R. 125: IX. The Cuckoo in the Depths of the Woods","artist":"Camille Saint-Saëns","album":"Saint-Saëns: Carnival of the Animals (Narrated by Jimmy Fallon)","type":"song","duration":135,"albumart":"https://i.scdn.co/image/ab67616d0000b273fab297a489e72fc64f81174e","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:609cexFubQ8JQ5u20Jbbkx","service":"spop","name":"Faded - Cello Version","artist":"Jodok Cello","album":"Faded (Cello Version)","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b273de3987f9dd7790b52a130512","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6hvo5KPU0Pk1Lc5vo6iVQB","service":"spop","name":"Carnival of the Animals, R. 125: IV. Tortoises","artist":"Camille Saint-Saëns","album":"Saint-Saëns","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b2739da4f5d3230e4f54339c12eb","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0KMiWpGnbzjs4eSGBvVr3o","service":"spop","name":"Piano Sonata No. 16 in C Major, K. 545 \"Sonata facile\": I. Allegro","artist":"Lang Lang","album":"Piano Book (Encore Edition)","type":"song","duration":294,"albumart":"https://i.scdn.co/image/ab67616d0000b27353094e73bbd934805d9cba02","samplerate":"96 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 17 18:56:25 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:56:25 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:27 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:56:27 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:28 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:56:28 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:31 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:56:31 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:31 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@1 Feb 17 18:56:31 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@1 in service upnp_browser Feb 17 18:56:36 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:56:36 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:36 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3740 Feb 17 18:56:36 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3741 Feb 17 18:56:36 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3742 Feb 17 18:56:36 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3743 Feb 17 18:56:36 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3744 Feb 17 18:56:36 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3244 Feb 17 18:56:36 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3740 in service upnp_browser Feb 17 18:56:36 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3741 in service upnp_browser Feb 17 18:56:36 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3742 in service upnp_browser Feb 17 18:56:37 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3743 in service upnp_browser Feb 17 18:56:37 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3744 in service upnp_browser Feb 17 18:56:37 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@3244 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:56:40 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2651 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2658 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2666 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2659 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2660 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2652 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2661 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2668 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2653 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2669 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2654 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2662 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2670 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2655 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2671 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2656 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2664 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2673 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2672 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2657 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2663 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2674 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2665 Feb 17 18:56:40 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2667 Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2651 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2658 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2666 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2659 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2660 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2652 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2661 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2668 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2653 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2669 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2654 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2662 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2670 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2655 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2671 in service upnp_browser Feb 17 18:56:40 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2656 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2664 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2673 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2672 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2657 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2663 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2674 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2665 in service upnp_browser Feb 17 18:56:41 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@22$@2667 in service upnp_browser Feb 17 18:56:42 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::ClearQueue Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::stPlaybackTimer Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::serviceStop Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::serviceStop Feb 17 18:56:42 znt volumio[1197]: info: Spotify Stop Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: SPOTIFY STOP Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: {"status":"play","position":0,"title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d0000b27386abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","codec":"ogg","seek":0,"duration":269,"samplerate":"96 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Feb 17 18:56:42 znt volumio[1197]: info: Sending Spotify command to local API: /player/pause Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::clearPlayQueue Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::addQueueItems Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::addQueueItems Feb 17 18:56:42 znt volumio[1197]: info: Preload queue cleared Feb 17 18:56:42 znt volumio[1197]: info: Adding Item to queue: upnp/folder/http://192.168.188.21:50001/ContentDirectory/control@22$483 Feb 17 18:56:42 znt volumio[1197]: info: Exploding uri upnp/folder/http://192.168.188.21:50001/ContentDirectory/control@22$483 in service upnp_browser Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:42 znt go-librespot[1697]: time="2025-02-17T18:56:42Z" level=debug msg="pause track at 34599ms" Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::saveQueue Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::updateTrackBlock Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrackBlock Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPlay Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::play index 0 Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::stop Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::play index undefined Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: info: [1739818602456] ControllerUPNPBrowser::clearAddPlayTrack Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand stop took 2 milliseconds Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 18:56:42 znt volumio[1197]: info: Feb 17 18:56:42 znt volumio[1197]: ---------------------------- MPD announces system playlist update Feb 17 18:56:42 znt volumio[1197]: info: Ignoring MPD Status Update Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand clear took 4 milliseconds Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.188.21:50002/m/MP3/2651.mp3" Feb 17 18:56:42 znt volumio[1197]: error: updateQueue error: null Feb 17 18:56:42 znt volumio[1197]: info: ------------------------------ 8ms Feb 17 18:56:42 znt go-librespot[1697]: time="2025-02-17T18:56:42Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.188.21:50002/m/MP3/2651.mp3" Feb 17 18:56:42 znt volumio[1197]: info: Feb 17 18:56:42 znt volumio[1197]: ---------------------------- MPD announces system playlist update Feb 17 18:56:42 znt volumio[1197]: info: Ignoring MPD Status Update Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand add "http://192.168.188.21:50002/m/MP3/2651.mp3" took 1 milliseconds Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand play Feb 17 18:56:42 znt volumio[1197]: info: ------------------------------ 2ms Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand play took 1 milliseconds Feb 17 18:56:42 znt go-librespot[1697]: time="2025-02-17T18:56:42Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 18:56:42 znt go-librespot[1697]: time="2025-02-17T18:56:42Z" level=trace msg="emitting websocket event: paused" Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","play_origin":"go-librespot"}} Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: PUSH STATE SPOTIFY Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: {"status":"pause","service":"spop","title":"Haltet die Liebe rein","artist":"Herzensmelodie","album":"Du bist mein","albumart":"https://i.scdn.co/image/ab67616d00001e0286abb7da03e3536f644ad5d1","uri":"spotify:track:6ZHaVH17yrzdIH6y7mQCcG","trackType":"spotify","seek":34000,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"96 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop Feb 17 18:56:42 znt volumio[1197]: info: Feb 17 18:56:42 znt volumio[1197]: ---------------------------- MPD announces state update: player Feb 17 18:56:42 znt volumio[1197]: info: ControllerMpd::getState Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:56:42 znt volumio[1197]: info: Feb 17 18:56:42 znt volumio[1197]: ---------------------------- MPD announces state update: player Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand status took 3 milliseconds Feb 17 18:56:42 znt volumio[1197]: info: ControllerMpd::getState Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::parseState Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand status took 2 milliseconds Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::parseState Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:56:42 znt volumio[1197]: info: ControllerMpd::pushState Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2651.mp3","artist":null,"album":null,"uri":"http://192.168.188.21:50002/m/MP3/2651.mp3","trackType":"mp3"} Feb 17 18:56:42 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:56:42 znt volumio[1197]: info: ------------------------------ 9ms Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:56:42 znt volumio[1197]: info: ControllerMpd::pushState Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2651.mp3","artist":null,"album":null,"uri":"http://192.168.188.21:50002/m/MP3/2651.mp3","trackType":"mp3"} Feb 17 18:56:42 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:42 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:42 znt volumio[1197]: info: ------------------------------ 26ms Feb 17 18:56:42 znt volumio[1197]: info: Feb 17 18:56:42 znt volumio[1197]: ---------------------------- MPD announces system playlist update Feb 17 18:56:42 znt volumio[1197]: info: Ignoring MPD Status Update Feb 17 18:56:42 znt volumio[1197]: info: Feb 17 18:56:42 znt volumio[1197]: ---------------------------- MPD announces state update: player Feb 17 18:56:42 znt volumio[1197]: info: ControllerMpd::getState Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:42 znt volumio[1197]: info: ------------------------------ 16ms Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand status took 15 milliseconds Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::parseState Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:56:42 znt volumio[1197]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 17 18:56:42 znt volumio[1197]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:56:42 znt volumio[1197]: info: ControllerMpd::pushState Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::servicePushState Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getTrack 0 Feb 17 18:56:42 znt volumio[1197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1104,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Track 01","artist":"All Creatures","album":"Album","uri":"http://192.168.188.21:50002/m/MP3/2651.mp3","trackType":"mp3"} Feb 17 18:56:42 znt volumio[1197]: verbose: CURRENT POSITION 0 Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::syncState stateService play Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:56:42 znt volumio[1197]: info: Received an update from plugin. extracting info from payload Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::pushState Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioPushState Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:56:42 znt volumio[1197]: info: MRS: Pushing multiroomSync output Feb 17 18:56:42 znt volumio[1197]: info: ------------------------------ 36ms Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::getQueue Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getQueue Feb 17 18:56:42 znt volumio[1197]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:56:42 znt volumio[1197]: info: CoreStateMachine::getQueue Feb 17 18:56:42 znt volumio[1197]: info: CorePlayQueue::getQueue Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:56:42 znt volumio[1197]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 18:57:07 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:57:07 znt volumio[1197]: info: Preload queue cleared Feb 17 18:57:08 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:57:10 znt volumio[1197]: info: Preload queue cleared Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@275 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18673 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31792 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18672 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31791 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18674 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31793 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18675 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31794 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18678 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31795 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18677 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31796 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18676 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31797 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18679 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31799 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18680 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31798 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18681 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31801 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18683 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31802 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18682 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31800 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18684 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31803 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@37467 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@15 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@16 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49158 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49190 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49193 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49192 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49191 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49198 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49195 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49194 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49196 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49197 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49519 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49518 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49521 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49522 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49523 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49525 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49524 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49526 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49529 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49527 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49528 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49531 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49530 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49532 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@22 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@23 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@24 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@25 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@26 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@28 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@27 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@30 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@29 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@33 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@32 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@35 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@34 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@36 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@37 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@38 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@39 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@40 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49536 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49535 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49538 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49539 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49540 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49541 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49544 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49542 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@46113 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@46115 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45840 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45839 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45786 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45788 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@837 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@838 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@839 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@840 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@841 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@843 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@842 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@844 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1472 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1471 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1473 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1474 Feb 17 18:57:10 znt volumio[1197]: info: Preloading song: upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1475 Feb 17 18:57:10 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@275 in service upnp_browser Feb 17 18:57:10 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18673 in service upnp_browser Feb 17 18:57:10 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31792 in service upnp_browser Feb 17 18:57:10 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18672 in service upnp_browser Feb 17 18:57:10 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31791 in service upnp_browser Feb 17 18:57:10 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18674 in service upnp_browser Feb 17 18:57:10 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31793 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18675 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31794 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18678 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31795 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18677 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31796 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18676 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31797 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18679 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31799 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18680 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31798 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18681 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31801 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18683 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31802 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18682 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31800 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@18684 in service upnp_browser Feb 17 18:57:11 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31803 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@37467 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@15 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@16 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49158 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49190 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49193 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49192 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49191 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49198 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49195 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49194 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49196 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49197 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49519 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49518 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49521 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49522 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49523 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49525 in service upnp_browser Feb 17 18:57:12 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49524 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49526 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49529 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49527 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49528 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49531 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49530 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49532 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@22 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@23 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@24 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@25 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@26 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@28 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@27 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@30 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@29 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@31 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@33 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@32 in service upnp_browser Feb 17 18:57:13 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@35 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@34 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@36 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@37 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@38 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@39 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@40 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49536 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49535 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49538 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49539 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49540 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49541 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49544 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@49542 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@46113 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@46115 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45840 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45839 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45786 in service upnp_browser Feb 17 18:57:14 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@45788 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@837 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@838 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@839 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@840 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@841 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@843 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@842 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@844 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1472 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1471 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1473 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1474 in service upnp_browser Feb 17 18:57:15 znt volumio[1197]: info: Exploding uri upnp/http://192.168.188.21:50001/ContentDirectory/control@23$@1475 in service upnp_browser Feb 17 18:57:16 znt wpa_supplicant[1081]: wlan0: WPA: Group rekeying completed with 4c:5e:0c:b5:06:d1 [GTK=CCMP] Feb 17 18:57:16 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:57:16 znt volumio[1197]: info: Preload queue cleared Feb 17 18:57:16 znt volumio[1197]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Feb 17 18:57:19 znt volumio[1197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 18:57:19 znt volumio[1197]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:57:19 znt volumio[1197]: TypeError: Cannot read property 'length' of undefined Feb 17 18:57:19 znt volumio[1197]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Feb 17 18:57:19 znt volumio[1197]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Feb 17 18:57:19 znt volumio[1197]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Feb 17 18:57:19 znt volumio[1197]: at Parser.emit (events.js:400:28) Feb 17 18:57:19 znt volumio[1197]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Feb 17 18:57:19 znt volumio[1197]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Feb 17 18:57:19 znt volumio[1197]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Feb 17 18:57:19 znt volumio[1197]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Feb 17 18:57:19 znt volumio[1197]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Feb 17 18:57:19 znt volumio[1197]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Feb 17 18:57:19 znt volumio[1197]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Feb 17 18:57:19 znt volumio[1197]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Feb 17 18:57:19 znt volumio[1197]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Feb 17 18:57:19 znt volumio[1197]: at IncomingMessage.emit (events.js:412:35) Feb 17 18:57:19 znt volumio[1197]: at endReadableNT (internal/streams/readable.js:1333:12) Feb 17 18:57:19 znt volumio[1197]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Feb 17 18:57:19 znt volumio[1197]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:57:19 znt sudo[32603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-17 18:56 Feb 17 18:57:19 znt sudo[32603]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"