-- Logs begin at Sat 2024-03-30 16:07:10 MSK, end at Sat 2024-04-13 10:48:59 MSK. -- Apr 13 10:47:02 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:08 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:14 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:15 volumio-3631 volumiologrotate[485]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 13 10:47:15 volumio-3631 volumiologrotate[485]: ls: cannot access '3_631': No such file or directory Apr 13 10:47:20 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:26 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:32 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:36 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 10:47:36 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 10:47:36 volumio-3631 volumio[910]: info: Discovery: Getting this device information Apr 13 10:47:36 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioGetState Apr 13 10:47:36 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 10:47:38 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:44 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:46 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 10:47:46 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 10:47:46 volumio-3631 volumio[910]: info: Discovery: Getting this device information Apr 13 10:47:46 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioGetState Apr 13 10:47:46 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::servicePause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::pause Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand pause took 0 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: info: Apr 13 10:47:50 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:50 volumio-3631 volumio[910]: info: Apr 13 10:47:50 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:50 volumio-3631 volumio[910]: info: Apr 13 10:47:50 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:50 volumio-3631 volumio[910]: info: Apr 13 10:47:50 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand status took 5 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand status took 4 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand status took 3 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand status took 1 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":117150,"duration":241,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Царица","artist":"ANNA ASTI","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713015952&ei=MDgaZtPGE8OVv_IP78GJqAs&ip=185.119.1.176&id=o-AOW4RCc2DDA-6HLyasxw-Tdrw4eF-0YpMVLE5FsOY54I&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Um&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a2pxYq325uFUszbRaVI_kvuZgvXTzYE4WKpgKlEQmeSKyB3MNu8sLEf019rfOfA5sPxq6Dw22Ab&spc=UWF9f38FI6bZR9p_tF-gLQZXgH0HD2RN2RPnHd6szLqVvIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fU1x8y8F-OrqjMz4J7TJ8qgQ&gir=yes&clen=3786919&dur=241.061&lmt=1705836638626047&mt=1712994052&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=Th4FDMqiE2e5cA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhALu8MbavV2CxQaVov6DBLbipEYa780Z78mVK5qW6mlu9AiA_kZVmWneRo3mXbVXCq-xcD2z4z3R9BxFzEPMCqbpUGg%3D%3D&sig=AJfQdSswRgIhAJZh46GkdK0ciRD4Cjb4gvBFwwneVcH7MBQbzECvu3isAiEA4uuPWB0OqUP_ELfsIWyL2sxgUMg5SOaORiYPnlhkAHA%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":117150,"duration":241,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Царица","artist":"ANNA ASTI","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713015952&ei=MDgaZtPGE8OVv_IP78GJqAs&ip=185.119.1.176&id=o-AOW4RCc2DDA-6HLyasxw-Tdrw4eF-0YpMVLE5FsOY54I&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Um&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a2pxYq325uFUszbRaVI_kvuZgvXTzYE4WKpgKlEQmeSKyB3MNu8sLEf019rfOfA5sPxq6Dw22Ab&spc=UWF9f38FI6bZR9p_tF-gLQZXgH0HD2RN2RPnHd6szLqVvIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fU1x8y8F-OrqjMz4J7TJ8qgQ&gir=yes&clen=3786919&dur=241.061&lmt=1705836638626047&mt=1712994052&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=Th4FDMqiE2e5cA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhALu8MbavV2CxQaVov6DBLbipEYa780Z78mVK5qW6mlu9AiA_kZVmWneRo3mXbVXCq-xcD2z4z3R9BxFzEPMCqbpUGg%3D%3D&sig=AJfQdSswRgIhAJZh46GkdK0ciRD4Cjb4gvBFwwneVcH7MBQbzECvu3isAiEA4uuPWB0OqUP_ELfsIWyL2sxgUMg5SOaORiYPnlhkAHA%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":117150,"duration":241,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Царица","artist":"ANNA ASTI","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713015952&ei=MDgaZtPGE8OVv_IP78GJqAs&ip=185.119.1.176&id=o-AOW4RCc2DDA-6HLyasxw-Tdrw4eF-0YpMVLE5FsOY54I&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Um&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a2pxYq325uFUszbRaVI_kvuZgvXTzYE4WKpgKlEQmeSKyB3MNu8sLEf019rfOfA5sPxq6Dw22Ab&spc=UWF9f38FI6bZR9p_tF-gLQZXgH0HD2RN2RPnHd6szLqVvIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fU1x8y8F-OrqjMz4J7TJ8qgQ&gir=yes&clen=3786919&dur=241.061&lmt=1705836638626047&mt=1712994052&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=Th4FDMqiE2e5cA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhALu8MbavV2CxQaVov6DBLbipEYa780Z78mVK5qW6mlu9AiA_kZVmWneRo3mXbVXCq-xcD2z4z3R9BxFzEPMCqbpUGg%3D%3D&sig=AJfQdSswRgIhAJZh46GkdK0ciRD4Cjb4gvBFwwneVcH7MBQbzECvu3isAiEA4uuPWB0OqUP_ELfsIWyL2sxgUMg5SOaORiYPnlhkAHA%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:47:50 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":117150,"duration":241,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Царица","artist":"ANNA ASTI","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713015952&ei=MDgaZtPGE8OVv_IP78GJqAs&ip=185.119.1.176&id=o-AOW4RCc2DDA-6HLyasxw-Tdrw4eF-0YpMVLE5FsOY54I&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Um&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a2pxYq325uFUszbRaVI_kvuZgvXTzYE4WKpgKlEQmeSKyB3MNu8sLEf019rfOfA5sPxq6Dw22Ab&spc=UWF9f38FI6bZR9p_tF-gLQZXgH0HD2RN2RPnHd6szLqVvIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fU1x8y8F-OrqjMz4J7TJ8qgQ&gir=yes&clen=3786919&dur=241.061&lmt=1705836638626047&mt=1712994052&fvip=17&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=Th4FDMqiE2e5cA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhALu8MbavV2CxQaVov6DBLbipEYa780Z78mVK5qW6mlu9AiA_kZVmWneRo3mXbVXCq-xcD2z4z3R9BxFzEPMCqbpUGg%3D%3D&sig=AJfQdSswRgIhAJZh46GkdK0ciRD4Cjb4gvBFwwneVcH7MBQbzECvu3isAiEA4uuPWB0OqUP_ELfsIWyL2sxgUMg5SOaORiYPnlhkAHA%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:50 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:50 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:47:50 volumio-3631 volumio[910]: info: ------------------------------ 43ms Apr 13 10:47:50 volumio-3631 volumio[910]: info: ------------------------------ 42ms Apr 13 10:47:50 volumio-3631 volumio[910]: info: ------------------------------ 42ms Apr 13 10:47:50 volumio-3631 volumio[910]: info: ------------------------------ 41ms Apr 13 10:47:50 volumio-3631 volumio[910]: info: peppy_screensaver: Start PeppyMeter Apr 13 10:47:50 volumio-3631 volumio[910]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 13 10:47:50 volumio-3631 volumio[910]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 13 10:47:50 volumio-3631 volumio[910]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 13 10:47:50 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:47:52 volumio-3631 volumio[910]: info: Preload queue cleared Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::ClearQueue Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::stop Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrackBlock Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::serviceStop Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::serviceStop Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::stop Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::clearPlayQueue Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::saveQueue Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::addQueueItems Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::addQueueItems Apr 13 10:47:52 volumio-3631 volumio[910]: info: Preload queue cleared Apr 13 10:47:52 volumio-3631 volumio[910]: info: Adding Item to queue: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22%D0%9C%D0%B0%D1%82%D1%83%D1%88%D0%BA%D0%B0%22%2C%22artist%22%3A%22%D0%A2%D0%B0%D1%82%D1%8C%D1%8F%D0%BD%D0%B0%20%D0%9A%D1%83%D1%80%D1%82%D1%83%D0%BA%D0%BE%D0%B2%D0%B0%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F3gCvRrF5SA4%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3kdH0t6DcteR2Vob2ZFHn5e9-bxWw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%223gCvRrF5SA4%22%2C%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%2C%22videoId%22%3A%22Ehg13UzgvQY%22%7D%7D%7D%7D Apr 13 10:47:52 volumio-3631 volumio[910]: info: Exploding uri ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22%D0%9C%D0%B0%D1%82%D1%83%D1%88%D0%BA%D0%B0%22%2C%22artist%22%3A%22%D0%A2%D0%B0%D1%82%D1%8C%D1%8F%D0%BD%D0%B0%20%D0%9A%D1%83%D1%80%D1%82%D1%83%D0%BA%D0%BE%D0%B2%D0%B0%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F3gCvRrF5SA4%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3kdH0t6DcteR2Vob2ZFHn5e9-bxWw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%223gCvRrF5SA4%22%2C%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%2C%22videoId%22%3A%22Ehg13UzgvQY%22%7D%7D%7D%7D in service ytmusic Apr 13 10:47:52 volumio-3631 volumio[910]: info: [ytmusic-browse] explodeUri: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22%D0%9C%D0%B0%D1%82%D1%83%D1%88%D0%BA%D0%B0%22%2C%22artist%22%3A%22%D0%A2%D0%B0%D1%82%D1%8C%D1%8F%D0%BD%D0%B0%20%D0%9A%D1%83%D1%80%D1%82%D1%83%D0%BA%D0%BE%D0%B2%D0%B0%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F3gCvRrF5SA4%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3kdH0t6DcteR2Vob2ZFHn5e9-bxWw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%223gCvRrF5SA4%22%2C%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%2C%22videoId%22%3A%22Ehg13UzgvQY%22%7D%7D%7D%7D Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::saveQueue Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrackBlock Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPlay Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::play index 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::stop Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::play index undefined Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22%D0%9C%D0%B0%D1%82%D1%83%D1%88%D0%BA%D0%B0%22%2C%22artist%22%3A%22%D0%A2%D0%B0%D1%82%D1%8C%D1%8F%D0%BD%D0%B0%20%D0%9A%D1%83%D1%80%D1%82%D1%83%D0%BA%D0%BE%D0%B2%D0%B0%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F3gCvRrF5SA4%2Fhq720.jpg%3Fsqp%3D-oaymwEXCKAGEMIDIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3kdH0t6DcteR2Vob2ZFHn5e9-bxWw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%223gCvRrF5SA4%22%2C%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL4fGSI1pDJn6cLcPmcc9b_l8oM0aJtsqL%22%2C%22videoId%22%3A%22Ehg13UzgvQY%22%7D%7D%7D%7D Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand stop took 37 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand status took 14 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand status took 13 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand status took 13 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand status took 12 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:47:52 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 86ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 83ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 83ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 83ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand stop took 5 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand clear took 1 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube" Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: error: updateQueue error: null Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 6ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand addid "https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube" took 6 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 5ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 4ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 4ms Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: MPD COMMAND [object Object] Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: MPD COMMAND [object Object] Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 8ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 5ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 5ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 3ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:47:52 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand play Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Apr 13 10:47:52 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:47:52 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 5ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: sendMpdCommand play took 5 milliseconds Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 4ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 3ms Apr 13 10:47:52 volumio-3631 volumio[910]: info: ------------------------------ 2ms Apr 13 10:47:53 volumio-3631 volumio[910]: info: Apr 13 10:47:53 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:53 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:53 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:53 volumio-3631 volumio[910]: info: Apr 13 10:47:53 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:53 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:53 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:53 volumio-3631 volumio[910]: info: Apr 13 10:47:53 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:53 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:53 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:53 volumio-3631 volumio[910]: info: Apr 13 10:47:53 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:53 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:54 volumio-3631 volumio[910]: info: Apr 13 10:47:54 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 210 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 208 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 208 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 206 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: info: Apr 13 10:47:54 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:54 volumio-3631 volumio[910]: info: Apr 13 10:47:54 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:54 volumio-3631 volumio[910]: info: Apr 13 10:47:54 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 12 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 5 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 4 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand status took 2 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:47:54 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:47:54 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:47:54 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 246ms Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 258ms Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 256ms Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 255ms Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 34 milliseconds Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:47:54 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:47:54 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:47:54 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:47:54 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:47:54 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 73ms Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 66ms Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 64ms Apr 13 10:47:54 volumio-3631 volumio[910]: info: ------------------------------ 64ms Apr 13 10:47:56 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:02 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:04 volumio-3631 volumio[910]: info: VolumeController::SetAlsaVolume77 Apr 13 10:48:04 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:04 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:48:04 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::servicePause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::pause Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: Apr 13 10:48:07 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand pause took 1 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:07 volumio-3631 volumio[910]: info: Apr 13 10:48:07 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:07 volumio-3631 volumio[910]: info: Apr 13 10:48:07 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:07 volumio-3631 volumio[910]: info: Apr 13 10:48:07 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand status took 3 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand status took 3 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand status took 2 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand status took 1 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13804,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13804,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13804,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:48:07 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13804,"duration":169,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Матушка","artist":"Татьяна Куртукова","album":null,"uri":"https://rr1---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016072&ei=qDgaZoSTD-Ctv_IP6pWReA&ip=185.119.1.176&id=o-AHYSTrnCFxG1mdj1t6q8xT9xc5RGOwaOPapDLbQxJb9a&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=G9&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7e&ms=au%2Crdu&mv=m&mvi=1&pl=22&initcwndbps=1541250&bui=AaUN6a1KOH0Df-3whugkPNF4ZTqKm1boArgG_c_RZov6b1CpoVYW3wLlcgasDc5iihrFLhy7sxVFTQSk&spc=UWF9fx-rjMhSyhWNJnk8SmJLnGdcEOogr18mGb6j1Lm3msw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=M7noTvXOPQGIgV4Y9xv9hhsQ&gir=yes&clen=2791499&dur=168.961&lmt=1710165854240809&mt=1712994052&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=5EXYSRREOMId6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAJd1uqCDcS5F_vV5iFaGlsWw7b65WFPzLc-Y13KGek8jAiEA3HsPsWQ7p2MhWdqIpCc07gSHJUsKv25cYstwgz-Guuo%3D&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAPuMj3p1PPK98Bb1e9bsN9rUCTn0xvhKCKljHEgzSkuQAiB3bEzC895XbCOUBGjBAVbvIVgDabEyi5EjPCYzPWRduA%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:07 volumio-3631 volumio[910]: verbose: CURRENT POSITION 0 Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus pause Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:07 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:48:07 volumio-3631 volumio[910]: info: ------------------------------ 48ms Apr 13 10:48:07 volumio-3631 volumio[910]: info: ------------------------------ 47ms Apr 13 10:48:07 volumio-3631 volumio[910]: info: ------------------------------ 47ms Apr 13 10:48:07 volumio-3631 volumio[910]: info: ------------------------------ 46ms Apr 13 10:48:07 volumio-3631 volumio[910]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 13 10:48:08 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:14 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:15 volumio-3631 volumiologrotate[485]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 13 10:48:15 volumio-3631 volumiologrotate[485]: ls: cannot access '3_631': No such file or directory Apr 13 10:48:16 volumio-3631 volumio[910]: Searching plugin music_service/ytmusic Apr 13 10:48:16 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Apr 13 10:48:17 volumio-3631 volumio[910]: info: All search sources collected, pushing search results Apr 13 10:48:18 volumio-3631 volumio[910]: Searching plugin music_service/ytmusic Apr 13 10:48:18 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Apr 13 10:48:19 volumio-3631 volumio[910]: info: All search sources collected, pushing search results Apr 13 10:48:20 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:22 volumio-3631 volumio[910]: Searching plugin music_service/ytmusic Apr 13 10:48:22 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Apr 13 10:48:23 volumio-3631 volumio[910]: info: All search sources collected, pushing search results Apr 13 10:48:24 volumio-3631 volumio[910]: Searching plugin music_service/ytmusic Apr 13 10:48:24 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Apr 13 10:48:24 volumio-3631 volumio[910]: info: All search sources collected, pushing search results Apr 13 10:48:26 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:30 volumio-3631 volumio[910]: info: Preload queue cleared Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::ClearQueue Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::stop Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrackBlock Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::serviceStop Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 0 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::serviceStop Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::stop Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::clearPlayQueue Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::saveQueue Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::addQueueItems Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::addQueueItems Apr 13 10:48:30 volumio-3631 volumio[910]: info: Preload queue cleared Apr 13 10:48:30 volumio-3631 volumio[910]: info: Adding Item to queue: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%22%2C%22artist%22%3A%22Z-Ro%22%2C%22album%22%3A%22Greatest%20Hits%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F8Oo6qS9GUFq1HBtHp3tqcGJ9K5p4kefL_h5PVMcc9ZvYgQ70OZcicQVNJMNpwKRcsCldnggWI7jY0EqqKQ%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kqywpzHDdR0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kqywpzHDdR0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 13 10:48:30 volumio-3631 volumio[910]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%22%2C%22artist%22%3A%22Z-Ro%22%2C%22album%22%3A%22Greatest%20Hits%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F8Oo6qS9GUFq1HBtHp3tqcGJ9K5p4kefL_h5PVMcc9ZvYgQ70OZcicQVNJMNpwKRcsCldnggWI7jY0EqqKQ%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kqywpzHDdR0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kqywpzHDdR0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic Apr 13 10:48:30 volumio-3631 volumio[910]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%22%2C%22artist%22%3A%22Z-Ro%22%2C%22album%22%3A%22Greatest%20Hits%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F8Oo6qS9GUFq1HBtHp3tqcGJ9K5p4kefL_h5PVMcc9ZvYgQ70OZcicQVNJMNpwKRcsCldnggWI7jY0EqqKQ%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kqywpzHDdR0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kqywpzHDdR0%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 13 10:48:30 volumio-3631 volumio[910]: info: Adding Item to queue: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%20River%22%2C%22artist%22%3A%22Mark%20Knopfler%22%2C%22album%22%3A%22One%20Deep%20River%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F4itKsoSj7zRL_ZhGtWBn7Dt3GPRQcU7M4stCkWv_EQW-l8XQtm0v3CO_HDIDk8-qiSAgmUe59_s-uhI%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 13 10:48:30 volumio-3631 volumio[910]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%20River%22%2C%22artist%22%3A%22Mark%20Knopfler%22%2C%22album%22%3A%22One%20Deep%20River%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F4itKsoSj7zRL_ZhGtWBn7Dt3GPRQcU7M4stCkWv_EQW-l8XQtm0v3CO_HDIDk8-qiSAgmUe59_s-uhI%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic Apr 13 10:48:30 volumio-3631 volumio[910]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%20River%22%2C%22artist%22%3A%22Mark%20Knopfler%22%2C%22album%22%3A%22One%20Deep%20River%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F4itKsoSj7zRL_ZhGtWBn7Dt3GPRQcU7M4stCkWv_EQW-l8XQtm0v3CO_HDIDk8-qiSAgmUe59_s-uhI%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 13 10:48:30 volumio-3631 volumio[910]: info: Adding Item to queue: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%22%2C%22artist%22%3A%22Z-Ro%22%2C%22album%22%3A%22I'm%20Still%20Living%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FQZulDcgc9pz8IkpaDCdF6uVhwS5CJ2bm8jVYAtZHC2S6BqbkOjqu2kG9jAJ-4PHW2Yi-xJLOu6rjhgc%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22R5w3BjPtpCs%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22R5w3BjPtpCs%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 13 10:48:30 volumio-3631 volumio[910]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%22%2C%22artist%22%3A%22Z-Ro%22%2C%22album%22%3A%22I'm%20Still%20Living%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FQZulDcgc9pz8IkpaDCdF6uVhwS5CJ2bm8jVYAtZHC2S6BqbkOjqu2kG9jAJ-4PHW2Yi-xJLOu6rjhgc%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22R5w3BjPtpCs%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22R5w3BjPtpCs%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic Apr 13 10:48:30 volumio-3631 volumio[910]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%22%2C%22artist%22%3A%22Z-Ro%22%2C%22album%22%3A%22I'm%20Still%20Living%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FQZulDcgc9pz8IkpaDCdF6uVhwS5CJ2bm8jVYAtZHC2S6BqbkOjqu2kG9jAJ-4PHW2Yi-xJLOu6rjhgc%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22R5w3BjPtpCs%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22R5w3BjPtpCs%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 13 10:48:30 volumio-3631 volumio[910]: info: Adding Item to queue: ytmusic/search@query=One%20deep@continuation:o=%7B%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22prevItemCount%22%3A0%7D@continuationBundle:o=%7B%22section%22%3A%7B%22title%22%3A%22Songs%22%2C%22subtitle%22%3Anull%2C%22filters%22%3A%5B%7B%22type%22%3A%22option%22%2C%22subtype%22%3A%22chipCloud%22%2C%22optionValues%22%3A%5B%7B%22text%22%3A%22Songs%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Videos%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIQAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Albums%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIYAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Featured%20playlists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgeKAQQoADgBahIQAxAEEAkQDhAKEAUQERAQEBU%253D%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Community%20playlists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgeKAQQoAEABahIQAxAEEAkQDhAKEAUQERAQEBU%253D%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Artists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIgAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Podcasts%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJQAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Episodes%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Profiles%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJYAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%5D%7D%5D%2C%22buttons%22%3Anull%2C%22filtersFromParent%22%3Atrue%2C%22buttonsFromParent%22%3Atrue%7D%2C%22contents%22%3A%7B%22header%22%3A%7B%22type%22%3A%22search%22%2C%22title%22%3A%22YouTube%20Music%20results%20for%20%5C%22One%20deep%5C%22%22%7D%2C%22tabs%22%3A%5B%7B%22type%22%3A%22tab%22%2C%22text%22%3A%22YT%20Music%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%7D%7D%2C%22selected%22%3Atrue%7D%5D%7D%7D@noExplode=1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: Exploding uri ytmusic/search@query=One%20deep@continuation:o=%7B%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22prevItemCount%22%3A0%7D@continuationBundle:o=%7B%22section%22%3A%7B%22title%22%3A%22Songs%22%2C%22subtitle%22%3Anull%2C%22filters%22%3A%5B%7B%22type%22%3A%22option%22%2C%22subtype%22%3A%22chipCloud%22%2C%22optionValues%22%3A%5B%7B%22text%22%3A%22Songs%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Videos%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIQAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Albums%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIYAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Featured%20playlists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgeKAQQoADgBahIQAxAEEAkQDhAKEAUQERAQEBU%253D%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Community%20playlists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgeKAQQoAEABahIQAxAEEAkQDhAKEAUQERAQEBU%253D%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Artists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIgAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Podcasts%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJQAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Episodes%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Profiles%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJYAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%5D%7D%5D%2C%22buttons%22%3Anull%2C%22filtersFromParent%22%3Atrue%2C%22buttonsFromParent%22%3Atrue%7D%2C%22contents%22%3A%7B%22header%22%3A%7B%22type%22%3A%22search%22%2C%22title%22%3A%22YouTube%20Music%20results%20for%20%5C%22One%20deep%5C%22%22%7D%2C%22tabs%22%3A%5B%7B%22type%22%3A%22tab%22%2C%22text%22%3A%22YT%20Music%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%7D%7D%2C%22selected%22%3Atrue%7D%5D%7D%7D@noExplode=1 in service ytmusic Apr 13 10:48:30 volumio-3631 volumio[910]: info: [ytmusic-browse] explodeUri: ytmusic/search@query=One%20deep@continuation:o=%7B%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22prevItemCount%22%3A0%7D@continuationBundle:o=%7B%22section%22%3A%7B%22title%22%3A%22Songs%22%2C%22subtitle%22%3Anull%2C%22filters%22%3A%5B%7B%22type%22%3A%22option%22%2C%22subtype%22%3A%22chipCloud%22%2C%22optionValues%22%3A%5B%7B%22text%22%3A%22Songs%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Videos%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIQAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Albums%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIYAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Featured%20playlists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgeKAQQoADgBahIQAxAEEAkQDhAKEAUQERAQEBU%253D%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Community%20playlists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgeKAQQoAEABahIQAxAEEAkQDhAKEAUQERAQEBU%253D%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Artists%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQIgAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Podcasts%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJQAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Episodes%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJIAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%2C%7B%22text%22%3A%22Profiles%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%2C%22params%22%3A%22EgWKAQJYAWoSEAMQBBAJEA4QChAFEBEQEBAV%22%7D%7D%2C%22selected%22%3Afalse%2C%22isReset%22%3Afalse%7D%5D%7D%5D%2C%22buttons%22%3Anull%2C%22filtersFromParent%22%3Atrue%2C%22buttonsFromParent%22%3Atrue%7D%2C%22contents%22%3A%7B%22header%22%3A%7B%22type%22%3A%22search%22%2C%22title%22%3A%22YouTube%20Music%20results%20for%20%5C%22One%20deep%5C%22%22%7D%2C%22tabs%22%3A%5B%7B%22type%22%3A%22tab%22%2C%22text%22%3A%22YT%20Music%22%2C%22endpoint%22%3A%7B%22type%22%3A%22search%22%2C%22payload%22%3A%7B%22query%22%3A%22One%20deep%22%7D%7D%2C%22selected%22%3Atrue%7D%5D%7D%7D@noExplode=1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::saveQueue Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrackBlock Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPlay Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::play index 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::stop Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::play index undefined Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22One%20Deep%20River%22%2C%22artist%22%3A%22Mark%20Knopfler%22%2C%22album%22%3A%22One%20Deep%20River%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2F4itKsoSj7zRL_ZhGtWBn7Dt3GPRQcU7M4stCkWv_EQW-l8XQtm0v3CO_HDIDk8-qiSAgmUe59_s-uhI%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22xsTszrOkiXo%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand stop took 42 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand status took 16 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand status took 12 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand status took 11 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand status took 10 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 54ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 52ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 49ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 49ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand stop took 0 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand clear took 1 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube" Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: error: updateQueue error: null Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 18ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand addid "https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube" took 15 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 14ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 13ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 12ms Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: MPD COMMAND [object Object] Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: MPD COMMAND [object Object] Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 19ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand play Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 10ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 7ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 6ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 4ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces system playlist update Apr 13 10:48:30 volumio-3631 volumio[910]: info: Ignoring MPD Status Update Apr 13 10:48:30 volumio-3631 volumio[910]: info: sendMpdCommand play took 6 milliseconds Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 3ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 2ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: ------------------------------ 2ms Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:30 volumio-3631 volumio[910]: info: Apr 13 10:48:30 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:30 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:30 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 207 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: info: Apr 13 10:48:31 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 208 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 207 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 205 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 212ms Apr 13 10:48:31 volumio-3631 volumio[910]: info: Apr 13 10:48:31 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:31 volumio-3631 volumio[910]: info: Apr 13 10:48:31 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:31 volumio-3631 volumio[910]: info: Apr 13 10:48:31 volumio-3631 volumio[910]: ---------------------------- MPD announces state update: player Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::getState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 6 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 5 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 5 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand status took 4 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseState Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:48:31 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:48:31 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:48:31 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 243ms Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 242ms Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 244ms Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:48:31 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:48:31 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:48:31 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: ControllerMpd::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::servicePushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"One Deep River","artist":"Mark Knopfler","album":"One Deep River","uri":"https://rr3---sn-vcpu-axql.googlevideo.com/videoplayback?expire=1713016110&ei=zjgaZuOAEdixv_IPs8CtmAs&ip=185.119.1.176&id=o-AICKRJDLu7HeTxrLLa4GhlG5_I97_H11aVhPQ97cVTYx&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kV&mm=31%2C29&mn=sn-vcpu-axql%2Csn-axq7sn7z&ms=au%2Crdu&mv=m&mvi=3&pl=22&gcr=ru&initcwndbps=1541250&bui=AaUN6a0Cv-Re7XYitvqJiulLNXsk7P46Gd8qiV6Xm_7ZmiMyb4MW71QPv1AEXp_jp1KoX2DxbztqB1pE&spc=UWF9f88oEcXpb86uNewwu3LOlnSRRvAUTMcIbfvSuYfwKOc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Qwe0gxQFX8Zkl8UKaExnh0YQ&gir=yes&clen=8732888&dur=257.361&lmt=1712566291151749&mt=1712994052&fvip=7&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=n8wejgqqFvYTGA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRQIhAK9meRNgbBmFwwxL7jkH9UIDbbGhLLIgYgd0btTs9GDcAiAZJJkqMBO73dOi-hjj22yMTIJxe4GG8TMZmXtaS5RcJg%3D%3D&sig=AJfQdSswRQIgWRcd4oESwxf5ljkybfDX9vmBDhDDHyFClv7a422Ff00CIQCl4bHwp-ufBb74sTe0y8CI8QuBT67nOjqSLpTanMdncg%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 13 10:48:31 volumio-3631 volumio[910]: verbose: CURRENT POSITION 1 Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState stateService play Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 13 10:48:31 volumio-3631 volumio[910]: info: Received an update from plugin. extracting info from payload Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 71ms Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 67ms Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 67ms Apr 13 10:48:31 volumio-3631 volumio[910]: info: ------------------------------ 66ms Apr 13 10:48:32 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:38 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:44 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:48 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 10:48:48 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 10:48:48 volumio-3631 volumio[910]: info: Discovery: Getting this device information Apr 13 10:48:48 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioGetState Apr 13 10:48:48 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 10:48:50 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:51 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 10:48:51 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 10:48:53 volumio-3631 volumio[910]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 13 10:48:53 volumio-3631 volumio[910]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 13 10:48:53 volumio-3631 volumio[910]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 9 Apr 13 10:48:53 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioGetState Apr 13 10:48:54 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesRadio Paradise Apr 13 10:48:54 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 10:48:54 volumio-3631 volumio[910]: Cannot find translation for source YouTube Music Apr 13 10:48:54 volumio-3631 volumio[910]: info: Disabling plugin radio_paradise Apr 13 10:48:54 volumio-3631 volumio[910]: info: Done. Apr 13 10:48:56 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music Apr 13 10:48:56 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 10:48:56 volumio-3631 volumio[910]: info: Disabling plugin ytmusic Apr 13 10:48:56 volumio-3631 volumio[910]: info: Done. Apr 13 10:48:56 volumio-3631 wpa_supplicant[746]: wlan0: Failed to initiate sched scan Apr 13 10:48:57 volumio-3631 volumio[910]: info: AutoStart - onStop Apr 13 10:48:57 volumio-3631 volumio[910]: info: Disabling plugin autostart Apr 13 10:48:57 volumio-3631 volumio[910]: info: Done. Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 10:48:58 volumio-3631 volumio[910]: info: Discovery: Getting this device information Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioGetState Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreStateMachine::stop Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 13 10:48:58 volumio-3631 volumio[910]: info: CorePlayQueue::getTrackBlock Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreStateMachine::pushState Apr 13 10:48:58 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreCommandRouter::volumioPushState Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreStateMachine::serviceStop Apr 13 10:48:58 volumio-3631 volumio[910]: info: CorePlayQueue::getTrack 1 Apr 13 10:48:58 volumio-3631 volumio[910]: info: CoreCommandRouter::serviceStop Apr 13 10:48:58 volumio-3631 volumio[910]: error: WARNING: No stop method for service ytmusic Apr 13 10:48:58 volumio-3631 volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 10:48:58 volumio-3631 volumio[910]: TypeError: Cannot read property 'then' of undefined Apr 13 10:48:58 volumio-3631 volumio[910]: at peppyScreensaver.onStop (/data/plugins/user_interface/peppy_screensaver/index.js:208:43) Apr 13 10:48:58 volumio-3631 volumio[910]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 13 10:48:58 volumio-3631 volumio[910]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 13 10:48:58 volumio-3631 volumio[910]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1531:29) Apr 13 10:48:58 volumio-3631 volumio[910]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1356:47) Apr 13 10:48:58 volumio-3631 volumio[910]: at Socket.emit (events.js:315:20) Apr 13 10:48:58 volumio-3631 volumio[910]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 13 10:48:58 volumio-3631 volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 13 10:48:58 volumio-3631 volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 10:48:59 volumio-3631 sudo[3054]: volumio : unable to resolve host volumio-3631 Apr 13 10:48:59 volumio-3631 sudo[3054]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 13 10:48:59 volumio-3631 sudo[3054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-13 10:47 Apr 13 10:48:59 volumio-3631 sudo[3054]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"