-- 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"