May 22 17:17:04 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri May 22 17:17:04 volumio volumio[1680]: info: [youtube2-browse] browseUri: youtube2 May 22 17:17:05 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:05 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:05 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:05 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:07 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri May 22 17:17:07 volumio volumio[1680]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEwhat_to_watch%22%7D%7D May 22 17:17:07 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 22 17:17:07 volumio dbus-daemon[955]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.39' (uid=0 pid=2827 comm="timedatectl show --property=NTPSynchronized --valu") May 22 17:17:07 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 22 17:17:07 volumio dbus-daemon[955]: [system] Successfully activated service 'org.freedesktop.timedate1' May 22 17:17:07 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 22 17:17:07 volumio setdatetime-helper.sh[2826]: Time is not synchronized. Attempting to sync... May 22 17:17:07 volumio sudo[2835]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 22 May 2025 08:17:08 GMT#015' May 22 17:17:07 volumio sudo[2835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 22 17:17:08 volumio setdatetime-helper.sh[2836]: Thu May 22 17:17:08 JST 2025 May 22 17:17:08 volumio sudo[2835]: pam_unix(sudo:session): session closed for user root May 22 17:17:08 volumio setdatetime-helper.sh[2826]: Time synchronized successfully. May 22 17:17:08 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 22 17:17:08 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 22 17:17:09 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:09 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:09 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:09 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:09 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:09 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:13 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::ClearQueue May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::stop May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::stPlaybackTimer May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::updateTrackBlock May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrackBlock May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 1 May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::serviceStop May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 1 May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::serviceStop May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::stop May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand stop May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::clearPlayQueue May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::saveQueue May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPushQueue May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::addQueueItems May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::addQueueItems May 22 17:17:13 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:13 volumio volumio[1680]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%E5%AE%87%E5%A4%9A%E7%94%B0%E3%83%92%E3%82%AB%E3%83%AB%C2%A0-%C2%A0First%C2%A0Love%C2%A0%2F%C2%A0THE%C2%A0FIRST%C2%A0TAKE%22%2C%22artist%22%3A%22THE%20FIRST%20TAKE%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiBUV7dxGhno%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAZuD8ukdUV7M5Tj6DOFCov0j6f1Q%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D%7D May 22 17:17:13 volumio volumio[1680]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%E5%AE%87%E5%A4%9A%E7%94%B0%E3%83%92%E3%82%AB%E3%83%AB%C2%A0-%C2%A0First%C2%A0Love%C2%A0%2F%C2%A0THE%C2%A0FIRST%C2%A0TAKE%22%2C%22artist%22%3A%22THE%20FIRST%20TAKE%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiBUV7dxGhno%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAZuD8ukdUV7M5Tj6DOFCov0j6f1Q%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D%7D in service youtube2 May 22 17:17:13 volumio volumio[1680]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%E5%AE%87%E5%A4%9A%E7%94%B0%E3%83%92%E3%82%AB%E3%83%AB%C2%A0-%C2%A0First%C2%A0Love%C2%A0%2F%C2%A0THE%C2%A0FIRST%C2%A0TAKE%22%2C%22artist%22%3A%22THE%20FIRST%20TAKE%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiBUV7dxGhno%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAZuD8ukdUV7M5Tj6DOFCov0j6f1Q%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D%7D May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPushQueue May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::saveQueue May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::updateTrackBlock May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrackBlock May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPlay May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::play index 0 May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::stop May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::play index undefined May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::startPlaybackTimer May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%E5%AE%87%E5%A4%9A%E7%94%B0%E3%83%92%E3%82%AB%E3%83%AB%C2%A0-%C2%A0First%C2%A0Love%C2%A0%2F%C2%A0THE%C2%A0FIRST%C2%A0TAKE%22%2C%22artist%22%3A%22THE%20FIRST%20TAKE%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiBUV7dxGhno%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAZuD8ukdUV7M5Tj6DOFCov0j6f1Q%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D%7D May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Volumio is not playing May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Clipped samples monitor stopped May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand stop took 20 milliseconds May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand status took 2 milliseconds May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand status took 1 milliseconds May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand status took 1 milliseconds May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand status took 1 milliseconds May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 22 17:17:13 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 17ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 18ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 17ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 17ms May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Volumio is not playing May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Clipped samples monitor stopped May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Volumio is not playing May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Clipped samples monitor stopped May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Volumio is not playing May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Clipped samples monitor stopped May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Volumio is not playing May 22 17:17:13 volumio volumio[1680]: info: FusionDsp - Clipped samples monitor stopped May 22 17:17:13 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand stop May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand stop took 1 milliseconds May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand clear May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand clear took 0 milliseconds May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube" May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: error: updateQueue error: null May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 5ms May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand addid "https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube" took 5 milliseconds May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 2ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 2ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 2ms May 22 17:17:13 volumio volumio[1680]: verbose: MPD COMMAND [object Object] May 22 17:17:13 volumio volumio[1680]: verbose: MPD COMMAND [object Object] May 22 17:17:13 volumio volumio[1680]: verbose: MPD COMMAND [object Object] May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 3ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 1ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 1ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 1ms May 22 17:17:13 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 17:17:13 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand play May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: May 22 17:17:13 volumio volumio[1680]: ---------------------------- MPD announces system playlist update May 22 17:17:13 volumio volumio[1680]: info: Ignoring MPD Status Update May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 6ms May 22 17:17:13 volumio volumio[1680]: info: sendMpdCommand play took 5 milliseconds May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 5ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 1ms May 22 17:17:13 volumio volumio[1680]: info: ------------------------------ 1ms May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 10 milliseconds May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 8 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 7 milliseconds May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - ---- read samplerate from file: 48000 May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: May 22 17:17:14 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 25 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 24 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 20 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 18 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 17 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 2 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 2 milliseconds May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus stop May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:14 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:14 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 45ms May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 49ms May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 46ms May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand status took 18 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 17 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 17 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 16 milliseconds May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 17 milliseconds May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:14 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:14 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:14 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:14 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 73ms May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 72ms May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 46ms May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 47ms May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 52 milliseconds May 22 17:17:14 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:14 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:14 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:14 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:14 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:14 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:14 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:14 volumio volumio[1680]: info: ------------------------------ 78ms May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:14 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:16 volumio volumio[1680]: info: FusionDsp - Clipping Monitor started May 22 17:17:20 volumio volumio[1680]: info: CoreCommandRouter::volumioGetState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioSeek May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::seek May 22 17:17:26 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:26 volumio volumio[1680]: info: TRACKBLOCK {"service":"youtube2","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%E5%AE%87%E5%A4%9A%E7%94%B0%E3%83%92%E3%82%AB%E3%83%AB%C2%A0-%C2%A0First%C2%A0Love%C2%A0%2F%C2%A0THE%C2%A0FIRST%C2%A0TAKE%22%2C%22artist%22%3A%22THE%20FIRST%20TAKE%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiBUV7dxGhno%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAZuD8ukdUV7M5Tj6DOFCov0j6f1Q%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D%7D","albumart":"https://i.ytimg.com/vi/iBUV7dxGhno/maxresdefault.jpg?v=6822a54d","artist":"THE FIRST TAKE","album":"YouTube","name":"宇多田ヒカル - First Love / THE FIRST TAKE","title":"宇多田ヒカル - First Love / THE FIRST TAKE","duration":314,"samplerate":"160 kbps"} May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::startPlaybackTimer May 22 17:17:26 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::seek May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: error: null May 22 17:17:26 volumio volumio[1680]: info: May 22 17:17:26 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:26 volumio volumio[1680]: info: May 22 17:17:26 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:26 volumio volumio[1680]: info: May 22 17:17:26 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:26 volumio volumio[1680]: info: May 22 17:17:26 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand status took 5 milliseconds May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand status took 4 milliseconds May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand status took 4 milliseconds May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand status took 3 milliseconds May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 17:17:26 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:26 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:26 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:26 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:26 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:26 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:26 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:26 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:26 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:26 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:26 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:26 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:26 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:26 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:26 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:26 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:26 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:26 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:26 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:26 volumio volumio[1680]: info: ------------------------------ 34ms May 22 17:17:26 volumio volumio[1680]: info: ------------------------------ 34ms May 22 17:17:26 volumio volumio[1680]: info: ------------------------------ 34ms May 22 17:17:26 volumio volumio[1680]: info: ------------------------------ 34ms May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:26 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioSeek May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::seek May 22 17:17:31 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:31 volumio volumio[1680]: info: TRACKBLOCK {"service":"youtube2","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%E5%AE%87%E5%A4%9A%E7%94%B0%E3%83%92%E3%82%AB%E3%83%AB%C2%A0-%C2%A0First%C2%A0Love%C2%A0%2F%C2%A0THE%C2%A0FIRST%C2%A0TAKE%22%2C%22artist%22%3A%22THE%20FIRST%20TAKE%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiBUV7dxGhno%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAZuD8ukdUV7M5Tj6DOFCov0j6f1Q%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iBUV7dxGhno%22%7D%7D%7D","albumart":"https://i.ytimg.com/vi/iBUV7dxGhno/maxresdefault.jpg?v=6822a54d","artist":"THE FIRST TAKE","album":"YouTube","name":"宇多田ヒカル - First Love / THE FIRST TAKE","title":"宇多田ヒカル - First Love / THE FIRST TAKE","duration":314,"samplerate":"160 kbps"} May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::startPlaybackTimer May 22 17:17:31 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::seek May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: error: null May 22 17:17:31 volumio volumio[1680]: info: May 22 17:17:31 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:31 volumio volumio[1680]: info: May 22 17:17:31 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:31 volumio volumio[1680]: info: May 22 17:17:31 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:31 volumio volumio[1680]: info: May 22 17:17:31 volumio volumio[1680]: ---------------------------- MPD announces state update: player May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::getState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand status May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand status took 4 milliseconds May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand status took 3 milliseconds May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand status took 3 milliseconds May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand status took 3 milliseconds May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseState May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 17:17:31 volumio volumio[1680]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:31 volumio volumio[1680]: verbose: ControllerMpd::parseTrackInfo May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:31 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:31 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:31 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:31 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:31 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:31 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:31 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:31 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:31 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:31 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:31 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:31 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: ControllerMpd::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::servicePushState May 22 17:17:31 volumio volumio[1680]: info: CorePlayQueue::getTrack 0 May 22 17:17:31 volumio volumio[1680]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63000,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"宇多田ヒカル - First Love / THE FIRST TAKE","artist":"THE FIRST TAKE","album":"YouTube","uri":"https://rr2---sn-punjvh-ioql.googlevideo.com/videoplayback?expire=1747923433&ei=id0uaPjoJsyWvcAPz7KRyAI&ip=101.111.165.62&id=o-AFGqYx0REBwcRWaGLNYm5PNEybinG8u7GsGkMuGA_hjf&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1747901833%2C&mh=Bs&mm=31%2C29&mn=sn-punjvh-ioql%2Csn-oguesndl&ms=au%2Crdu&mv=m&mvi=2&pl=18&rms=au%2Cau&ctier=A&pfa=5&initcwndbps=2955000&hightc=yes&siu=1&bui=AecWEAbhlG3rIplGDriqMLWerjEMTx32OHPq_0Hmhf-fZ8dgtjaXKHeuazAJIm3AD7DDQI-2Fg&spc=wk1kZpfRdN9NaXeK6kx9mR5EPTuINw29ymB7p4OkphpC7vDGNR0UKOqGWGBRTbSVwAZdY5jx9Btb&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XcGou-99znch9EDepG4t4QkQ&rqh=1&gir=yes&clen=5449892&dur=313.641&lmt=1747355834956668&mt=1747901579&fvip=3&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=3308224&n=vAHMbGzOLzQ0yQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhALWKU51qcI-1XM93Ps5vCGFbmf7xO9Y3GrGUhytBCRzyAiEAoQW2RQYk3jZaPCD2YpWAmNTnZtPYWOqWtYxpgibZ7Jc%3D&sig=AJfQdSswRgIhAPaxjEWl3LLBqEK-Z0oCED5qrksrWknmADRxBgNrA1U6AiEAyhsC2iNQq6oCkve74SSA0Ka6zyCCzup2YjqN9gJIkkI%3D&pot=Ml8HxpkF4T5-cQf6IEtib1DC0BbTFsQ-0suXUde695qBNcLe8nXwlEn8V8t5qAM3LuFGJ7fGNFKUik9jgF-C-9F8Np021bdvlE4zCY3m-UUE1fbUystDYTHoYOIT767WDA%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 22 17:17:31 volumio volumio[1680]: verbose: CURRENT POSITION 0 May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState stateService play May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::syncState currentStatus play May 22 17:17:31 volumio volumio[1680]: info: Received an update from plugin. extracting info from payload May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: CoreStateMachine::pushState May 22 17:17:31 volumio volumio[1680]: info: CoreCommandRouter::volumioPushState May 22 17:17:31 volumio volumio[1680]: info: ------------------------------ 33ms May 22 17:17:31 volumio volumio[1680]: info: ------------------------------ 34ms May 22 17:17:31 volumio volumio[1680]: info: ------------------------------ 33ms May 22 17:17:31 volumio volumio[1680]: info: ------------------------------ 33ms May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:31 volumio volumio[1680]: info: FusionDsp - Volumio is playing May 22 17:17:37 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 22 17:17:42 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri May 22 17:17:42 volumio volumio[1680]: info: [ytmusic-browse] browseUri: ytmusic May 22 17:17:42 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:48 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 17:17:48 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:50 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 17:17:50 volumio volumio[1680]: info: TuneIn handleBrowseUri: tunein/location May 22 17:17:51 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:53 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 17:17:53 volumio volumio[1680]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101218 May 22 17:17:53 volumio volumio[1680]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101218 May 22 17:17:53 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:55 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 17:17:55 volumio volumio[1680]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100436 May 22 17:17:55 volumio volumio[1680]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100436 May 22 17:17:55 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:55 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:58 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 17:17:58 volumio volumio[1680]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100455 May 22 17:17:58 volumio volumio[1680]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100455 May 22 17:17:59 volumio volumio[1680]: info: Preload queue cleared May 22 17:17:59 volumio volumio[1680]: info: Preload queue cleared May 22 17:18:01 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 17:18:01 volumio volumio[1680]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100004 May 22 17:18:01 volumio volumio[1680]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100004 May 22 17:18:01 volumio volumio[1680]: info: Preload queue cleared May 22 17:18:01 volumio volumio[1680]: info: Preload queue cleared May 22 17:18:06 volumio volumio[1680]: Searching plugin music_service/webradio May 22 17:18:06 volumio volumio[1680]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 22 17:18:07 volumio volumio[1680]: Use XMLElement.setAttribute instead May 22 17:18:07 volumio volumio[1680]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:18:07 volumio volumio[1680]: TypeError: children[i].attr(...).value is not a function May 22 17:18:07 volumio volumio[1680]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 22 17:18:07 volumio volumio[1680]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 22 17:18:07 volumio volumio[1680]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:18:07 volumio sudo[2968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-22 17:17' May 22 17:18:07 volumio sudo[2968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="26d819930af572ef184e636a18ef1127de78f391" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"