-- Logs begin at Sat 2024-04-27 17:14:41 CEST, end at Sat 2024-04-27 17:20:35 CEST. -- Apr 27 17:19:01 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:01+02:00" level=debug msg="fetched chunk 6/13, size: 524288" Apr 27 17:19:02 volumio-mixer volumio[597]: info: Preload queue cleared Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::ClearQueue Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::stop Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::stPlaybackTimer Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::updateTrackBlock Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::getTrackBlock Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 3 Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::serviceStop Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 3 Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::serviceStop Apr 27 17:19:02 volumio-mixer volumio[597]: info: Spotify Stop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: SPOTIFY STOP Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","position":3,"title":"Summertime","artist":"Miles Davis","album":"Porgy And Bess","albumart":"https://i.scdn.co/image/ab67616d0000b2736d7683097cc1f201aa935d39","uri":"spotify:track:25H0Wd1ugPRXGM2LlpjVXM","trackType":"spotify","codec":"ogg","seek":1,"duration":197,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Apr 27 17:19:02 volumio-mixer volumio[597]: info: Sending Spotify command to local API: /player/pause Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::clearPlayQueue Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::saveQueue Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushQueue Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::addQueueItems Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::addQueueItems Apr 27 17:19:02 volumio-mixer volumio[597]: info: Preload queue cleared Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:44E1amu24J4b5Sq95pZnL9 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:44E1amu24J4b5Sq95pZnL9 in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:44E1amu24J4b5Sq95pZnL9 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:2nHKSoU1OHlEGy7uc3owB8 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:2nHKSoU1OHlEGy7uc3owB8 in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:2nHKSoU1OHlEGy7uc3owB8 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:0rB3nKgLqrIsionaLrinVR Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:0rB3nKgLqrIsionaLrinVR in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:0rB3nKgLqrIsionaLrinVR Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:5xxemahwWNKOiSXk0wRdRY Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:5xxemahwWNKOiSXk0wRdRY in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:5xxemahwWNKOiSXk0wRdRY Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:5A6jmxAEpy6nSoqo6sROKN Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:5A6jmxAEpy6nSoqo6sROKN in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:5A6jmxAEpy6nSoqo6sROKN Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:3U0lyGvI5ueLmQYrbXspqU Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:3U0lyGvI5ueLmQYrbXspqU in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:3U0lyGvI5ueLmQYrbXspqU Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:4KjbBDEougPmUXRJfbzi44 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:4KjbBDEougPmUXRJfbzi44 in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:4KjbBDEougPmUXRJfbzi44 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:4e4i3pDkExZxiXGyiYEDLz Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:4e4i3pDkExZxiXGyiYEDLz in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:4e4i3pDkExZxiXGyiYEDLz Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:2jlm0GcwicxkRvWSBfWWbb Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:2jlm0GcwicxkRvWSBfWWbb in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:2jlm0GcwicxkRvWSBfWWbb Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:7pqzYY8oo1dsfK0WLDvbJ0 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:7pqzYY8oo1dsfK0WLDvbJ0 in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:7pqzYY8oo1dsfK0WLDvbJ0 Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:1Av6GaNGyDmrwxkl0WIVQs Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:1Av6GaNGyDmrwxkl0WIVQs in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:1Av6GaNGyDmrwxkl0WIVQs Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:1Xltv8se0m2iAv6xCgcQ9D Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:1Xltv8se0m2iAv6xCgcQ9D in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:1Xltv8se0m2iAv6xCgcQ9D Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:1Hula3qAEMYyKFN6YKSZqT Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:1Hula3qAEMYyKFN6YKSZqT in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:1Hula3qAEMYyKFN6YKSZqT Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:6isOeYaK0Pdfk1NWnpu2hF Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:6isOeYaK0Pdfk1NWnpu2hF in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:6isOeYaK0Pdfk1NWnpu2hF Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:2EIJkR2fkRo8s0NQ7wFPjz Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:2EIJkR2fkRo8s0NQ7wFPjz in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:2EIJkR2fkRo8s0NQ7wFPjz Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:7ekrDuqhYiAa2Zkbod80ZN Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:7ekrDuqhYiAa2Zkbod80ZN in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:7ekrDuqhYiAa2Zkbod80ZN Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:2T492Jt5OMQDs6Emra8cRf Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:2T492Jt5OMQDs6Emra8cRf in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:2T492Jt5OMQDs6Emra8cRf Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:5ZgM5k73gwBKTCryBjA8mo Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:5ZgM5k73gwBKTCryBjA8mo in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:5ZgM5k73gwBKTCryBjA8mo Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:1RfBWbMwDlVy6g2WfftQMk Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:1RfBWbMwDlVy6g2WfftQMk in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:1RfBWbMwDlVy6g2WfftQMk Apr 27 17:19:02 volumio-mixer volumio[597]: info: Adding Item to queue: spotify:track:6DV2Cpszs2jS1P3KDu8LXn Apr 27 17:19:02 volumio-mixer volumio[597]: info: Exploding uri spotify:track:6DV2Cpszs2jS1P3KDu8LXn in service spop Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: EXPLODING URI:spotify:track:6DV2Cpszs2jS1P3KDu8LXn Apr 27 17:19:02 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:02+02:00" level=debug msg="pause track at 45525ms" Apr 27 17:19:02 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:02 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:02 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:02+02:00" level=trace msg="emitting websocket event: paused" Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:25H0Wd1ugPRXGM2LlpjVXM","play_origin":"go-librespot"}} Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:02 volumio-mixer volumio[597]: SPOTIFY: {"status":"pause","service":"spop","title":"Summertime","artist":"Miles Davis","album":"Porgy And Bess","albumart":"https://i.scdn.co/image/ab67616d00001e026d7683097cc1f201aa935d39","uri":"spotify:track:25H0Wd1ugPRXGM2LlpjVXM","trackType":"spotify","seek":45001,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 3 Apr 27 17:19:02 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Summertime","artist":"Miles Davis","album":"Porgy And Bess","albumart":"https://i.scdn.co/image/ab67616d00001e026d7683097cc1f201aa935d39","uri":"spotify:track:25H0Wd1ugPRXGM2LlpjVXM","trackType":"spotify","seek":45001,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:02 volumio-mixer volumio[597]: verbose: CURRENT POSITION 3 Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService pause Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus stop Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:02 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 3 Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:02 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:25H0Wd1ugPRXGM2LlpjVXM","play_origin":"go-librespot"}} Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: {"status":"pause","service":"spop","title":"Summertime","artist":"Miles Davis","album":"Porgy And Bess","albumart":"https://i.scdn.co/image/ab67616d00001e026d7683097cc1f201aa935d39","uri":"spotify:track:25H0Wd1ugPRXGM2LlpjVXM","trackType":"spotify","seek":45001,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 3 Apr 27 17:19:03 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Summertime","artist":"Miles Davis","album":"Porgy And Bess","albumart":"https://i.scdn.co/image/ab67616d00001e026d7683097cc1f201aa935d39","uri":"spotify:track:25H0Wd1ugPRXGM2LlpjVXM","trackType":"spotify","seek":45001,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:03 volumio-mixer volumio[597]: verbose: CURRENT POSITION 3 Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService pause Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus stop Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 3 Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:03 volumio-mixer volumio[597]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","service":"spop","name":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","type":"song","duration":352,"albumart":"https://i.scdn.co/image/ab67616d0000b27358dccdc06dafe0d0dc170283","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5A6jmxAEpy6nSoqo6sROKN","service":"spop","name":"Los Endos - Live at Royal Albert Hall 2013","artist":"Steve Hackett","album":"Genesis Revisited: Live at the Royal Albert Hall","type":"song","duration":535,"albumart":"https://i.scdn.co/image/ab67616d0000b27304276a5a8e71407b9289f2a6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EIJkR2fkRo8s0NQ7wFPjz","service":"spop","name":"To Los Dias Me Pregunto (Remix) [feat. Ozuna, Bennybenni & Gotay]","artist":"Endo","album":"Straight from Pr","type":"song","duration":347,"albumart":"https://i.scdn.co/image/ab67616d0000b27304a3ea7d9a6f56a65051a66f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3U0lyGvI5ueLmQYrbXspqU","service":"spop","name":"Los Endos","artist":"Djabe","album":"Life is a Journey, The Budapest Live Tapes","type":"song","duration":453,"albumart":"https://i.scdn.co/image/ab67616d0000b2730281918e92a21e81bc21fd2a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ekrDuqhYiAa2Zkbod80ZN","service":"spop","name":"To los Dias Me Pregunto (Remix) [feat. Gotay, Ozuna & Benny Benni]","artist":"Endo","album":"To los Dias Me Pregunto (Remix) [feat. Gotay, Ozuna & Benny Benni]","type":"song","duration":347,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6c1105e3a937d69422627d3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Hula3qAEMYyKFN6YKSZqT","service":"spop","name":"Los Endos","artist":"Regenesis","album":"Here It Comes Again...","type":"song","duration":353,"albumart":"https://i.scdn.co/image/ab67616d0000b273c3ab4669a03feb3c02e04c22","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Av6GaNGyDmrwxkl0WIVQs","service":"spop","name":"Los Endos","artist":"Patrick Moraz","album":"Asia & Progressive Rock Friends","type":"song","duration":361,"albumart":"https://i.scdn.co/image/ab67616d0000b273d06f4f1bf25df43593ccfce1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6DV2Cpszs2jS1P3KDu8LXn","service":"spop","name":"Buena Vision","artist":"Los Encinos De Sinaloa","album":"Vasito De Jarabe","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b273cea62f6f5de7d95b899b1130","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2jlm0GcwicxkRvWSBfWWbb","service":"spop","name":"The Los Endos Suite - Live; 2019 Remaster","artist":"Phil Collins","album":"A Hot Night in Paris (Live; 2019 Remaster)","type":"song","duration":626,"albumart":"https://i.scdn.co/image/ab67616d0000b273afb2336a131ddabdc6dd2bc1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Xltv8se0m2iAv6xCgcQ9D","service":"spop","name":"Los Endos - Live","artist":"Steve Hackett","album":"The Tokyo Tapes","type":"song","duration":414,"albumart":"https://i.scdn.co/image/ab67616d0000b273bc3c0921ca50a89b70129ae8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0rB3nKgLqrIsionaLrinVR","service":"spop","name":"Los Endos - Live at Twickenham","artist":"Genesis","album":"Live Over Europe, 2007","type":"song","duration":384,"albumart":"https://i.scdn.co/image/ab67616d0000b27383566d45618ae139f6d5b360","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4e4i3pDkExZxiXGyiYEDLz","service":"spop","name":"Los Endos","artist":"Steve Hackett","album":"Summer Storms & Rocking Rivers","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b2735715f313ec4b2e15e18df84a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6isOeYaK0Pdfk1NWnpu2hF","service":"spop","name":"Los Dueños Del Malianteo","artist":"El Jordan 23","album":"Los Dueños Del Malianteo","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b273a879f9396b7cde7d78418c7a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4KjbBDEougPmUXRJfbzi44","service":"spop","name":"Los Endos","artist":"Djabe","album":"Life is a Journey, The Budapest Live Tapes","type":"song","duration":453,"albumart":"https://i.scdn.co/image/ab67616d0000b273d08000d7464d3efe2eba503b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5xxemahwWNKOiSXk0wRdRY","service":"spop","name":"Los Endos","artist":"Steve Hackett","album":"Genesis Revisited I (Re-Issue 2013)","type":"song","duration":531,"albumart":"https://i.scdn.co/image/ab67616d0000b2733040cf3e67fbdebeef266bec","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ZgM5k73gwBKTCryBjA8mo","service":"spop","name":"Seguimos Sumando (En vivo)","artist":"Los Encinos De Sinaloa","album":"Pa Cuando Me Enfiesto (En vivo)","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b2732406a7aae5f09deb44562e76","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7pqzYY8oo1dsfK0WLDvbJ0","service":"spop","name":"Los Endos - Platinum Collection Version","artist":"Genesis","album":"Platinum Collection","type":"song","duration":346,"albumart":"https://i.scdn.co/image/ab67616d0000b273c3575952bf375d857d00ac3b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1RfBWbMwDlVy6g2WfftQMk","service":"spop","name":"Cuando Un Amor","artist":"Los Encinos De Sinaloa","album":"La Revisada","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b27369b57845fcebff4d1969f6fe","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2nHKSoU1OHlEGy7uc3owB8","service":"spop","name":"Los Endos - Live in Paris","artist":"Genesis","album":"Seconds Out (Live)","type":"song","duration":436,"albumart":"https://i.scdn.co/image/ab67616d0000b2735908ad420ba0dd5c0da7742c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2T492Jt5OMQDs6Emra8cRf","service":"spop","name":"Endorphines","artist":"NRMNT","album":"Endorphines","type":"song","duration":292,"albumart":"https://i.scdn.co/image/ab67616d0000b273439c825132b745a55291cb55","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushQueue Apr 27 17:19:03 volumio-mixer volumio[597]: info: CorePlayQueue::saveQueue Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::updateTrackBlock Apr 27 17:19:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrackBlock Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPlay Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::play index 0 Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::stop Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::play index undefined Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:03 volumio-mixer volumio[597]: info: CoreStateMachine::startPlaybackTimer Apr 27 17:19:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:03 volumio-mixer volumio[597]: info: [1714231143764] ControllerSpotify::clearAddPlayTrack Apr 27 17:19:03 volumio-mixer volumio[597]: info: Sending Spotify command with payload to local API: /player/play Apr 27 17:19:03 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:03+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Apr 27 17:19:03 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:03+02:00" level=debug msg="loading track spotify:track:44E1amu24J4b5Sq95pZnL9 (paused: false, position: 1ms)" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=trace msg="emitting websocket event: will_play" Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:44E1amu24J4b5Sq95pZnL9" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=debug msg="requested aes key for file 4be25059cc7ec252e507aba9fd17d8199a742e82, gid: 44E1amu24J4b5Sq95pZnL9" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=debug msg="fetched first chunk of 27, total size is 13978380 bytes" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" Apr 27 17:19:04 volumio-mixer kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=info msg="loaded track \"Los Endos - 2007 Remaster\" (uri: spotify:track:44E1amu24J4b5Sq95pZnL9, paused: false, position: 1ms, duration: 352240ms)" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=trace msg="emitting websocket event: metadata" Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","name":"Los Endos - 2007 Remaster","artist_names":["Genesis"],"album_name":"A Trick of the Tail (2007 Remaster)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","position":1,"duration":352240,"release_date":"year:1976 month:2 day:2","track_number":8,"disc_number":1}} Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","name":"Los Endos - 2007 Remaster","artist_names":["Genesis"],"album_name":"A Trick of the Tail (2007 Remaster)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","position":1,"duration":352240,"release_date":"year:1976 month:2 day:2","track_number":8,"disc_number":1}} Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=debug msg="fetched chunk 3/26, size: 524288" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:04 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:04+02:00" level=trace msg="emitting websocket event: playing" Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:04 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:04 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:04 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus stop Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:04 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:04 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:04 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:04 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:04 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:04 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:04 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:05 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:05+02:00" level=debug msg="fetched chunk 2/26, size: 524288" Apr 27 17:19:05 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:05+02:00" level=debug msg="fetched chunk 1/26, size: 524288" Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:05 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:05 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:05 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:05 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":1,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:05 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:05 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:05 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:05 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:05 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 17:19:09 volumio-mixer volumiologrotate[404]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 27 17:19:09 volumio-mixer volumiologrotate[404]: ls: cannot access 'MIXER': No such file or directory Apr 27 17:19:12 volumio-mixer volumio[597]: info: VolumeController::SetAlsaVolume56 Apr 27 17:19:12 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:12 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:12 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:12 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:12 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Apr 27 17:19:12 volumio-mixer volumio[597]: SPOTIFY: SPOTIFY VOLUME 100 Apr 27 17:19:12 volumio-mixer volumio[597]: SPOTIFY: VOLUMIO VOLUME 56 Apr 27 17:19:12 volumio-mixer volumio[597]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 27 17:19:12 volumio-mixer volumio[597]: info: Setting Spotify Volume from Volumio: 56 Apr 27 17:19:12 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Apr 27 17:19:13 volumio-mixer volumio[597]: info: VolumeController::SetAlsaVolume77 Apr 27 17:19:13 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:13 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:13 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:13 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: SPOTIFY VOLUME 56 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: VOLUMIO VOLUME 77 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 27 17:19:13 volumio-mixer volumio[597]: info: Setting Spotify Volume from Volumio: 77 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 77 Apr 27 17:19:13 volumio-mixer volumio[597]: info: VolumeController::SetAlsaVolume45 Apr 27 17:19:13 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:13 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:13 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:13 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: SPOTIFY VOLUME 77 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: VOLUMIO VOLUME 45 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 27 17:19:13 volumio-mixer volumio[597]: info: Setting Spotify Volume from Volumio: 45 Apr 27 17:19:13 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:15 volumio-mixer volumio[597]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Apr 27 17:19:15 volumio-mixer volumio[597]: info: Sending Spotify command with payload to local API: /player/volume Apr 27 17:19:15 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:15+02:00" level=debug msg="update volume to 29490/65535" Apr 27 17:19:15 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:15+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 27 17:19:15 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:15+02:00" level=trace msg="emitting websocket event: volume" Apr 27 17:19:15 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Apr 27 17:19:15 volumio-mixer volumio[597]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Apr 27 17:19:15 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Apr 27 17:19:15 volumio-mixer volumio[597]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Apr 27 17:19:17 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:17+02:00" level=debug msg="fetched chunk 4/26, size: 524288" Apr 27 17:19:21 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioGetState Apr 27 17:19:21 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:24 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 27 17:19:24 volumio-mixer volumio[597]: info: Preload queue cleared Apr 27 17:19:25 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 27 17:19:25 volumio-mixer volumio[597]: info: Getting webradio selection Apr 27 17:19:26 volumio-mixer volumio[597]: info: Preload queue cleared Apr 27 17:19:32 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:32+02:00" level=debug msg="fetched chunk 5/26, size: 524288" Apr 27 17:19:39 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:39+02:00" level=debug msg="handling pause player command from a02fbb3b050d9cd6f634c45f404f7892dd0310b7" Apr 27 17:19:39 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:39+02:00" level=debug msg="pause track at 35534ms" Apr 27 17:19:40 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:40 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:40+02:00" level=debug msg="sending successful reply for delaer request" Apr 27 17:19:40 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:40 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:40+02:00" level=trace msg="emitting websocket event: paused" Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: {"status":"pause","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:40 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:40 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:40 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService pause Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:40 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: {"status":"pause","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:40 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:40 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:40 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService pause Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:40 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:40 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:40 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:46 volumio-mixer volumio[597]: info: Preload queue cleared Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::ClearQueue Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::stop Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::stPlaybackTimer Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::updateTrackBlock Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrackBlock Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::serviceStop Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::serviceStop Apr 27 17:19:46 volumio-mixer volumio[597]: info: Spotify Stop Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: SPOTIFY STOP Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","position":0,"title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d0000b27358dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","codec":"ogg","seek":35061,"duration":352,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":45,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Apr 27 17:19:46 volumio-mixer volumio[597]: info: Sending Spotify command to local API: /player/pause Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::clearPlayQueue Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::saveQueue Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushQueue Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::addQueueItems Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::addQueueItems Apr 27 17:19:46 volumio-mixer volumio[597]: info: Preload queue cleared Apr 27 17:19:46 volumio-mixer volumio[597]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushQueue Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::saveQueue Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::updateTrackBlock Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrackBlock Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPlay Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::play index 0 Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::stop Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::play index undefined Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::startPlaybackTimer Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:46 volumio-mixer volumio[597]: info: [1714231186284] ControllerWebradio::clearAddPlayTrack Apr 27 17:19:46 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 17:19:46 volumio-mixer volumio[597]: info: sendMpdCommand stop took 9 milliseconds Apr 27 17:19:46 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand clear Apr 27 17:19:46 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:46+02:00" level=debug msg="pause track at 37298ms" Apr 27 17:19:46 volumio-mixer volumio[597]: info: Apr 27 17:19:46 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:46 volumio-mixer volumio[597]: info: sendMpdCommand clear took 45 milliseconds Apr 27 17:19:46 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" Apr 27 17:19:46 volumio-mixer volumio[597]: info: Apr 27 17:19:46 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Apr 27 17:19:46 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:46 volumio-mixer volumio[597]: error: updateQueue error: null Apr 27 17:19:46 volumio-mixer volumio[597]: info: ------------------------------ 43ms Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:46 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:46 volumio-mixer volumio[597]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 27 17:19:46 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:46+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:19:46 volumio-mixer go-librespot[1628]: time="2024-04-27T17:19:46+02:00" level=trace msg="emitting websocket event: paused" Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: {"status":"pause","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:46 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:19:46 volumio-mixer volumio[597]: SPOTIFY: {"status":"pause","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:46 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:46 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Apr 27 17:19:46 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" Apr 27 17:19:46 volumio-mixer volumio[597]: error: updateQueue error: null Apr 27 17:19:46 volumio-mixer volumio[597]: error: updateQueue error: null Apr 27 17:19:46 volumio-mixer volumio[597]: info: Apr 27 17:19:46 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:46 volumio-mixer volumio[597]: info: ------------------------------ 541ms Apr 27 17:19:46 volumio-mixer volumio[597]: info: ------------------------------ 537ms Apr 27 17:19:46 volumio-mixer volumio[597]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 7 milliseconds Apr 27 17:19:46 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 17:19:46 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand play Apr 27 17:19:46 volumio-mixer volumio[597]: info: Apr 27 17:19:46 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Apr 27 17:19:46 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:46 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:46 volumio-mixer volumio[597]: info: ------------------------------ 14ms Apr 27 17:19:46 volumio-mixer volumio[597]: info: sendMpdCommand play took 9 milliseconds Apr 27 17:19:46 volumio-mixer volumio[597]: info: ------------------------------ 8ms Apr 27 17:19:46 volumio-mixer volumio[597]: info: ------------------------------ 5ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 25 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 29 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 26 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aacp_96","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus stop Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 56ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:47 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 35 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 16 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 13 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aacp_96","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aacp_96","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 155ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 154ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:47 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces system playlist update Apr 27 17:19:47 volumio-mixer volumio[597]: info: Ignoring MPD Status Update Apr 27 17:19:47 volumio-mixer volumio[597]: info: Apr 27 17:19:47 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 124ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 117 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 115 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 110 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 109 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 34ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 22 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 20ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand status took 16 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Diana Krall - This Can't Be Love","artist":"/SwissJazz_Room2.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"102 Kbps","isStreaming":false,"title":"Diana Krall - This Can't Be Love","artist":"/SwissJazz_Room2.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"102 Kbps","isStreaming":false,"title":"Diana Krall - This Can't Be Love","artist":"/SwissJazz_Room2.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 276ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 254ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 252ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 137 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 133 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 134 milliseconds Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"102 Kbps","isStreaming":false,"title":"Diana Krall - This Can't Be Love","artist":"/SwissJazz_Room2.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"102 Kbps","isStreaming":false,"title":"Diana Krall - This Can't Be Love","artist":"/SwissJazz_Room2.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1113,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"102 Kbps","isStreaming":false,"title":"Diana Krall - This Can't Be Love","artist":"/SwissJazz_Room2.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Apr 27 17:19:47 volumio-mixer volumio[597]: verbose: CURRENT POSITION 0 Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState stateService play Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::syncState currentStatus play Apr 27 17:19:47 volumio-mixer volumio[597]: info: Received an update from plugin. extracting info from payload Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 339ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 245ms Apr 27 17:19:47 volumio-mixer volumio[597]: info: ------------------------------ 231ms Apr 27 17:19:47 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:47 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:48 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:19:49 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioStop Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::stop Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::stPlaybackTimer Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::updateTrackBlock Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrackBlock Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::serviceStop Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::serviceStop Apr 27 17:20:03 volumio-mixer volumio[597]: info: [1714231203616] ControllerWebradio::stop Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 17:20:03 volumio-mixer volumio[597]: info: Apr 27 17:20:03 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:20:03 volumio-mixer volumio[597]: info: sendMpdCommand stop took 36 milliseconds Apr 27 17:20:03 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:20:03 volumio-mixer volumio[597]: info: Apr 27 17:20:03 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:20:03 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:20:03 volumio-mixer volumio[597]: info: Apr 27 17:20:03 volumio-mixer volumio[597]: ---------------------------- MPD announces state update: player Apr 27 17:20:03 volumio-mixer volumio[597]: info: ControllerMpd::getState Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand status Apr 27 17:20:03 volumio-mixer volumio[597]: info: sendMpdCommand status took 24 milliseconds Apr 27 17:20:03 volumio-mixer volumio[597]: info: sendMpdCommand status took 22 milliseconds Apr 27 17:20:03 volumio-mixer volumio[597]: info: sendMpdCommand status took 16 milliseconds Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::parseState Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 17:20:03 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 27 17:20:03 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 27 17:20:03 volumio-mixer volumio[597]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:20:03 volumio-mixer volumio[597]: verbose: ControllerMpd::parseTrackInfo Apr 27 17:20:03 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 27 17:20:03 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 27 17:20:03 volumio-mixer volumio[597]: info: ControllerMpd::pushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreStateMachine::pushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioPushState Apr 27 17:20:03 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:03 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 27 17:20:03 volumio-mixer volumio[597]: info: ------------------------------ 107ms Apr 27 17:20:03 volumio-mixer volumio[597]: info: ------------------------------ 104ms Apr 27 17:20:03 volumio-mixer volumio[597]: info: ------------------------------ 99ms Apr 27 17:20:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:03 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:03 volumio-mixer volumio[597]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 27 17:20:04 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:04 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:04 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:04 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:04 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:04 volumio-mixer volumio[597]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=debug msg="handling resume player command from a02fbb3b050d9cd6f634c45f404f7892dd0310b7" Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=debug msg="vorbis: corrupt or missing data in bitstream" Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=trace msg="seek to 37298ms (diff: 279ms, samples: 1644841, bytes: 1300958)" Apr 27 17:20:07 volumio-mixer kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=debug msg="resume track at 36649ms" Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=debug msg="sending successful reply for delaer request" Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 27 17:20:07 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:07+02:00" level=trace msg="emitting websocket event: playing" Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:20:07 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:20:07 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:07 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","play_origin":"go-librespot"}} Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:20:07 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:20:07 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:07 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:20:07 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:20:07 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:07 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: PUSH STATE SPOTIFY Apr 27 17:20:07 volumio-mixer volumio[597]: SPOTIFY: {"status":"play","service":"spop","title":"Los Endos - 2007 Remaster","artist":"Genesis","album":"A Trick of the Tail (2007 Remaster)","albumart":"https://i.scdn.co/image/ab67616d00001e0258dccdc06dafe0d0dc170283","uri":"spotify:track:44E1amu24J4b5Sq95pZnL9","trackType":"spotify","seek":35001,"duration":352,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 27 17:20:07 volumio-mixer volumio[597]: info: CoreCommandRouter::servicePushState Apr 27 17:20:07 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:07 volumio-mixer volumio[597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Apr 27 17:20:09 volumio-mixer volumiologrotate[404]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 27 17:20:09 volumio-mixer volumiologrotate[404]: ls: cannot access 'MIXER': No such file or directory Apr 27 17:20:13 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:13+02:00" level=debug msg="fetched chunk 6/26, size: 524288" Apr 27 17:20:13 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 27 17:20:13 volumio-mixer volumio[597]: info: CURURI: music-library Apr 27 17:20:13 volumio-mixer volumio[597]: error: Failed LSINFO: null Apr 27 17:20:13 volumio-mixer volumio[597]: info: Preload queue cleared Apr 27 17:20:19 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioGetState Apr 27 17:20:19 volumio-mixer volumio[597]: info: CorePlayQueue::getTrack 0 Apr 27 17:20:25 volumio-mixer go-librespot[1628]: time="2024-04-27T17:20:25+02:00" level=debug msg="fetched chunk 7/26, size: 524288" Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 17:20:26 volumio-mixer volumio[597]: info: Retrieving Cloud Streaming UI Apr 27 17:20:26 volumio-mixer volumio[597]: info: Getting Tidal Cloud Configuration Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 17:20:26 volumio-mixer volumio[597]: info: Getting Qobuz Cloud Configuration Apr 27 17:20:26 volumio-mixer volumio[597]: info: Asking plugin for UI Config Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 17:20:26 volumio-mixer volumio[597]: info: Getting Spotify Cloud Configuration Apr 27 17:20:26 volumio-mixer volumio[597]: info: Asking plugin for UI Config Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 17:20:26 volumio-mixer volumio[597]: info: Saving Spotify Acccount Apr 27 17:20:26 volumio-mixer volumio[597]: info: Got Tidal Cloud Configuration Apr 27 17:20:26 volumio-mixer volumio[597]: info: Got it Apr 27 17:20:26 volumio-mixer volumio[597]: info: Got it Apr 27 17:20:26 volumio-mixer volumio[597]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 17:20:26 volumio-mixer volumio[597]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 17:20:26 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 27 17:20:29 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 27 17:20:29 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 27 17:20:30 volumio-mixer volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 17:20:34 volumio-mixer volumio[597]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 17:20:34 volumio-mixer volumio[597]: Error: dns service error: unknown Apr 27 17:20:34 volumio-mixer volumio[597]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Apr 27 17:20:34 volumio-mixer volumio[597]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 27 17:20:34 volumio-mixer volumio[597]: errorCode: -65537 Apr 27 17:20:34 volumio-mixer volumio[597]: } Apr 27 17:20:34 volumio-mixer volumio[597]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 17:20:35 volumio-mixer sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 17:19 Apr 27 17:20:35 volumio-mixer sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"