May 31 12:54:01 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:01 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: youtube2 , goto May 31 12:54:02 jeanremyvolumio volumio[29740]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC07udoHCKUb36kImuvm5zJw%22%7D%7D May 31 12:54:09 jeanremyvolumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 31 12:54:34 jeanremyvolumio volumio[29740]: info: Preload queue cleared May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::ClearQueue May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stop May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stPlaybackTimer May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::updateTrackBlock May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrackBlock May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::serviceStop May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::serviceStop May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 12:54:34 jeanremyvolumio volumio[29740]: info: ControllerMpd::stop May 31 12:54:34 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand stop May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::clearPlayQueue May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::saveQueue May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushQueue May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::addQueueItems May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::addQueueItems May 31 12:54:34 jeanremyvolumio volumio[29740]: info: Preload queue cleared May 31 12:54:34 jeanremyvolumio volumio[29740]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:34 jeanremyvolumio volumio[29740]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D in service youtube2 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushQueue May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::saveQueue May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::updateTrackBlock May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrackBlock May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPlay May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::play index 0 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stop May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::play index undefined May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::startPlaybackTimer May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 12:54:34 jeanremyvolumio volumio[29740]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:34 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is not playing May 31 12:54:34 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipped samples monitor stopped May 31 12:54:34 jeanremyvolumio volumio[29740]: info: May 31 12:54:34 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:34 jeanremyvolumio volumio[29740]: info: sendMpdCommand stop took 84 milliseconds May 31 12:54:34 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:34 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:34 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 13 milliseconds May 31 12:54:34 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:34 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:54:34 jeanremyvolumio volumio[29740]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 12:54:34 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseTrackInfo May 31 12:54:34 jeanremyvolumio volumio[29740]: info: ControllerMpd::pushState May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::servicePushState May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:34 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:34 jeanremyvolumio volumio[29740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 31 12:54:34 jeanremyvolumio volumio[29740]: info: ------------------------------ 36ms May 31 12:54:34 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is not playing May 31 12:54:34 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipped samples monitor stopped May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:35 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand stop May 31 12:54:35 jeanremyvolumio volumio[29740]: info: sendMpdCommand stop took 10 milliseconds May 31 12:54:35 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand clear May 31 12:54:35 jeanremyvolumio volumio[29740]: info: May 31 12:54:35 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:35 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:35 jeanremyvolumio volumio[29740]: info: sendMpdCommand clear took 27 milliseconds May 31 12:54:35 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710474&ei=6t86aMmxFbOizPsPl9_G0AE&ip=176.147.19.217&id=o-ALX5_xsLRWCq38UWeiI6c_nVAw7pO12DeJvHUPtUPVnb&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688874%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25ge7nzs&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLNwySS1p65-gLVk4kGa_Y-JdU5tkHMqJf3Cr8TMZDeml_Em_IBzLi1XILyTreV9Wie33g&spc=l3OVKZxGdp1iC3apvSlFEyxpZ59g-1yp9uO94Dx6-hUMp0wTOg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=o0ZPls_mHMOMEJ1k1Vsr1uwQ&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=2&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=k07uUvI9Y8DTrg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAON7MuxWihYT0lHa_D-oHyumeBIUYRSmLUtCxtawisgJAiEAjpEIng0JcUU6t4pF_GgIzKOS7du56VlwhrXnB2s8RVQ%3D&sig=AJfQdSswRQIhAOm1OcB7-kXvhqXz0tBwbOeNNYxDu0b5D9KDKPIdVZIqAiBhGcWzsKEcK694Q86rmZKQsVVFGipB8DHLnaXgpbXqzQ%3D%3D&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube" May 31 12:54:35 jeanremyvolumio volumio[29740]: error: updateQueue error: null May 31 12:54:35 jeanremyvolumio volumio[29740]: info: ------------------------------ 6ms May 31 12:54:35 jeanremyvolumio volumio[29740]: info: May 31 12:54:35 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:35 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:35 jeanremyvolumio volumio[29740]: info: sendMpdCommand addid "https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710474&ei=6t86aMmxFbOizPsPl9_G0AE&ip=176.147.19.217&id=o-ALX5_xsLRWCq38UWeiI6c_nVAw7pO12DeJvHUPtUPVnb&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688874%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25ge7nzs&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLNwySS1p65-gLVk4kGa_Y-JdU5tkHMqJf3Cr8TMZDeml_Em_IBzLi1XILyTreV9Wie33g&spc=l3OVKZxGdp1iC3apvSlFEyxpZ59g-1yp9uO94Dx6-hUMp0wTOg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=o0ZPls_mHMOMEJ1k1Vsr1uwQ&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=2&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=k07uUvI9Y8DTrg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAON7MuxWihYT0lHa_D-oHyumeBIUYRSmLUtCxtawisgJAiEAjpEIng0JcUU6t4pF_GgIzKOS7du56VlwhrXnB2s8RVQ%3D&sig=AJfQdSswRQIhAOm1OcB7-kXvhqXz0tBwbOeNNYxDu0b5D9KDKPIdVZIqAiBhGcWzsKEcK694Q86rmZKQsVVFGipB8DHLnaXgpbXqzQ%3D%3D&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube" took 7 milliseconds May 31 12:54:35 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:35 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:35 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:35 jeanremyvolumio volumio[29740]: info: May 31 12:54:35 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:35 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:35 jeanremyvolumio volumio[29740]: info: ------------------------------ 8ms May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 12:54:35 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand play May 31 12:54:35 jeanremyvolumio volumio[29740]: info: ------------------------------ 6ms May 31 12:54:35 jeanremyvolumio volumio[29740]: info: sendMpdCommand play took 6 milliseconds May 31 12:54:35 jeanremyvolumio volumio[29740]: info: Preload queue cleared May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::ClearQueue May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stop May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::clearPlayQueue May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::saveQueue May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushQueue May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::addQueueItems May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::addQueueItems May 31 12:54:35 jeanremyvolumio volumio[29740]: info: Preload queue cleared May 31 12:54:35 jeanremyvolumio volumio[29740]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:35 jeanremyvolumio volumio[29740]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushQueue May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::saveQueue May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::updateTrackBlock May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrackBlock May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPlay May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::play index 0 May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stop May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::play index undefined May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreStateMachine::startPlaybackTimer May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:54:35 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 12:54:35 jeanremyvolumio volumio[29740]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:36 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:36 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand stop May 31 12:54:39 jeanremyvolumio volumio[29740]: info: May 31 12:54:39 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:39 jeanremyvolumio volumio[29740]: info: sendMpdCommand stop took 2767 milliseconds May 31 12:54:39 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand clear May 31 12:54:39 jeanremyvolumio volumio[29740]: info: May 31 12:54:39 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:39 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:39 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 7 milliseconds May 31 12:54:39 jeanremyvolumio volumio[29740]: info: sendMpdCommand clear took 7 milliseconds May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710475&ei=6986aLTCMK73xN8PwJCX-A4&ip=176.147.19.217&id=o-ACRGERL7qlgLsPOJo-NLH2TlP7yRHMgNDa80H39S66Ey&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688875%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25ge7nzs&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLPBEt1OL8ISbHjyYqV7dZ7yelSpRRjPPkTUhe_6LES3ZactvtHvB5pLk7S0wctFIMt_mg&spc=l3OVKaJ6o8QKJy_eqRnQg40faZUiJYFLobGZSxiWAYqno11MsA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gQ6VvxUCLDUplgMRaFFX9Z8Q&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=2&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=LeawzglVNRHUNw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgK4pLRXwXXXcGTdAxEHn4puhNmGfiNEvLIvQmp2kOiHUCIQCirBGIEmQKpBZGgw0W7GjolrRt4nJ1IPkYX_DzylRlMA%3D%3D&sig=AJfQdSswRgIhAOkovkPNBypXe_NYGOGs2fhF9vc_8Ln2wpC753o0L1UjAiEAsIqtX8rRu_JejaBqFUZwIzGxiiT_-0ZSMsZOYNBDWMs%3D&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube" May 31 12:54:39 jeanremyvolumio volumio[29740]: error: updateQueue error: null May 31 12:54:39 jeanremyvolumio volumio[29740]: info: May 31 12:54:39 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:39 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:39 jeanremyvolumio volumio[29740]: info: ------------------------------ 13ms May 31 12:54:39 jeanremyvolumio volumio[29740]: info: sendMpdCommand playlistinfo took 8 milliseconds May 31 12:54:39 jeanremyvolumio volumio[29740]: info: sendMpdCommand addid "https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710475&ei=6986aLTCMK73xN8PwJCX-A4&ip=176.147.19.217&id=o-ACRGERL7qlgLsPOJo-NLH2TlP7yRHMgNDa80H39S66Ey&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688875%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25ge7nzs&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLPBEt1OL8ISbHjyYqV7dZ7yelSpRRjPPkTUhe_6LES3ZactvtHvB5pLk7S0wctFIMt_mg&spc=l3OVKaJ6o8QKJy_eqRnQg40faZUiJYFLobGZSxiWAYqno11MsA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gQ6VvxUCLDUplgMRaFFX9Z8Q&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=2&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=LeawzglVNRHUNw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgK4pLRXwXXXcGTdAxEHn4puhNmGfiNEvLIvQmp2kOiHUCIQCirBGIEmQKpBZGgw0W7GjolrRt4nJ1IPkYX_DzylRlMA%3D%3D&sig=AJfQdSswRgIhAOkovkPNBypXe_NYGOGs2fhF9vc_8Ln2wpC753o0L1UjAiEAsIqtX8rRu_JejaBqFUZwIzGxiiT_-0ZSMsZOYNBDWMs%3D&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube" took 7 milliseconds May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseTrackInfo May 31 12:54:39 jeanremyvolumio volumio[29740]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:39 jeanremyvolumio volumio[29740]: info: ------------------------------ 30ms May 31 12:54:39 jeanremyvolumio volumio[29740]: info: May 31 12:54:39 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:39 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:39 jeanremyvolumio volumio[29740]: info: ------------------------------ 18ms May 31 12:54:39 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 12:54:39 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand play May 31 12:54:39 jeanremyvolumio volumio[29740]: info: ------------------------------ 7ms May 31 12:54:39 jeanremyvolumio volumio[29740]: info: sendMpdCommand play took 4 milliseconds May 31 12:54:40 jeanremyvolumio volumio[29740]: info: May 31 12:54:40 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:40 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - ---- read samplerate from file: 48000 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: camilladsp stopping service pid 28075... May 31 12:54:40 jeanremyvolumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! May 31 12:54:40 jeanremyvolumio volumio[29740]: info: camilladsp service terminated, instance 1 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 12:54:40 jeanremyvolumio volumio[29740]: info: camilladsp service started and running in background, instance 1 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - ---- read samplerate from file: 48000 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 12:54:40 jeanremyvolumio volumio[29740]: info: May 31 12:54:40 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:40 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 90 milliseconds May 31 12:54:40 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:54:40 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 9 milliseconds May 31 12:54:40 jeanremyvolumio volumio[29740]: info: sendMpdCommand playlistinfo took 8 milliseconds May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseTrackInfo May 31 12:54:40 jeanremyvolumio volumio[29740]: info: ControllerMpd::pushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::servicePushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Apashe & Vladimir Cauchemar - Bellatores (Official Music Video)","artist":"vladimir cauchemar","album":"YouTube","uri":"https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710475&ei=6986aLTCMK73xN8PwJCX-A4&ip=176.147.19.217&id=o-ACRGERL7qlgLsPOJo-NLH2TlP7yRHMgNDa80H39S66Ey&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688875%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25ge7nzs&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLPBEt1OL8ISbHjyYqV7dZ7yelSpRRjPPkTUhe_6LES3ZactvtHvB5pLk7S0wctFIMt_mg&spc=l3OVKaJ6o8QKJy_eqRnQg40faZUiJYFLobGZSxiWAYqno11MsA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gQ6VvxUCLDUplgMRaFFX9Z8Q&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=2&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=LeawzglVNRHUNw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgK4pLRXwXXXcGTdAxEHn4puhNmGfiNEvLIvQmp2kOiHUCIQCirBGIEmQKpBZGgw0W7GjolrRt4nJ1IPkYX_DzylRlMA%3D%3D&sig=AJfQdSswRgIhAOkovkPNBypXe_NYGOGs2fhF9vc_8Ln2wpC753o0L1UjAiEAsIqtX8rRu_JejaBqFUZwIzGxiiT_-0ZSMsZOYNBDWMs%3D&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: CURRENT POSITION 0 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState stateService play May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState currentStatus stop May 31 12:54:40 jeanremyvolumio volumio[29740]: info: ------------------------------ 118ms May 31 12:54:40 jeanremyvolumio volumio[29740]: error: FusionDsp - Reload WebSocket error: [object Object] May 31 12:54:40 jeanremyvolumio volumio[29740]: info: sendMpdCommand playlistinfo took 38 milliseconds May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseTrackInfo May 31 12:54:40 jeanremyvolumio volumio[29740]: info: ControllerMpd::pushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::servicePushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: STATE SERVICE {"status":"play","position":0,"seek":870,"duration":135,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Apashe & Vladimir Cauchemar - Bellatores (Official Music Video)","artist":"vladimir cauchemar","album":"YouTube","uri":"https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710475&ei=6986aLTCMK73xN8PwJCX-A4&ip=176.147.19.217&id=o-ACRGERL7qlgLsPOJo-NLH2TlP7yRHMgNDa80H39S66Ey&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688875%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25ge7nzs&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLPBEt1OL8ISbHjyYqV7dZ7yelSpRRjPPkTUhe_6LES3ZactvtHvB5pLk7S0wctFIMt_mg&spc=l3OVKaJ6o8QKJy_eqRnQg40faZUiJYFLobGZSxiWAYqno11MsA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gQ6VvxUCLDUplgMRaFFX9Z8Q&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=2&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=LeawzglVNRHUNw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgK4pLRXwXXXcGTdAxEHn4puhNmGfiNEvLIvQmp2kOiHUCIQCirBGIEmQKpBZGgw0W7GjolrRt4nJ1IPkYX_DzylRlMA%3D%3D&sig=AJfQdSswRgIhAOkovkPNBypXe_NYGOGs2fhF9vc_8Ln2wpC753o0L1UjAiEAsIqtX8rRu_JejaBqFUZwIzGxiiT_-0ZSMsZOYNBDWMs%3D&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 31 12:54:40 jeanremyvolumio volumio[29740]: verbose: CURRENT POSITION 0 May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState stateService play May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState currentStatus play May 31 12:54:40 jeanremyvolumio volumio[29740]: info: Received an update from plugin. extracting info from payload May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:40 jeanremyvolumio volumio[29740]: info: ------------------------------ 89ms May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is playing May 31 12:54:40 jeanremyvolumio volumio[29740]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is playing May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipping Monitor started May 31 12:54:40 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipping Monitor started May 31 12:54:42 jeanremyvolumio volumio[29740]: info: Preload queue cleared May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::ClearQueue May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stop May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stPlaybackTimer May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::updateTrackBlock May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrackBlock May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::serviceStop May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::serviceStop May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 12:54:42 jeanremyvolumio volumio[29740]: info: ControllerMpd::stop May 31 12:54:42 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand stop May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::clearPlayQueue May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::saveQueue May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushQueue May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::addQueueItems May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::addQueueItems May 31 12:54:42 jeanremyvolumio volumio[29740]: info: Preload queue cleared May 31 12:54:42 jeanremyvolumio volumio[29740]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:42 jeanremyvolumio volumio[29740]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushQueue May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::saveQueue May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::updateTrackBlock May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrackBlock May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPlay May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::play index 0 May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stop May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::play index undefined May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::startPlaybackTimer May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 12:54:42 jeanremyvolumio volumio[29740]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Apashe%20%26%20Vladimir%20Cauchemar%20-%20Bellatores%20(Official%20Music%20Video)%22%2C%22artist%22%3A%22vladimir%20cauchemar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F-GIdk_0wJ84%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBZHQj5itLeV8PHciDQH2-B4s9wgg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22-GIdk_0wJ84%22%7D%7D%7D May 31 12:54:42 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is not playing May 31 12:54:42 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipped samples monitor stopped May 31 12:54:42 jeanremyvolumio volumio[29740]: info: May 31 12:54:42 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:42 jeanremyvolumio volumio[29740]: info: sendMpdCommand stop took 70 milliseconds May 31 12:54:42 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:42 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:42 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 3 milliseconds May 31 12:54:42 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:42 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:54:42 jeanremyvolumio volumio[29740]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 12:54:42 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseTrackInfo May 31 12:54:42 jeanremyvolumio volumio[29740]: info: ControllerMpd::pushState May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::servicePushState May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:42 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:42 jeanremyvolumio volumio[29740]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 31 12:54:42 jeanremyvolumio volumio[29740]: info: ------------------------------ 20ms May 31 12:54:42 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is not playing May 31 12:54:42 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipped samples monitor stopped May 31 12:54:43 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:43 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand stop May 31 12:54:43 jeanremyvolumio volumio[29740]: info: sendMpdCommand stop took 6 milliseconds May 31 12:54:43 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand clear May 31 12:54:43 jeanremyvolumio volumio[29740]: info: May 31 12:54:43 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:43 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:43 jeanremyvolumio volumio[29740]: info: sendMpdCommand clear took 26 milliseconds May 31 12:54:43 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710483&ei=8t86aPupOqeM6dsP8rjvkAY&ip=176.147.19.217&id=o-AJ55qoHtTluH7a1jwHXvHcfiALaHrMi8St3jTuU0ruEA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688882%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25glenlr&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLMm6EDQgEevTApKw31ez3bcX8AiZR_D7gfHSCpMc0hyUG4nOFPbfd7mgtLQ5eeOSA7Row&spc=l3OVKcAaZ17PP3MP2UNwnUFyYFXV7-bo2hZe8u5Haq_VHSI9ZQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gKN10WCPXyX8r-1meKRLWnIQ&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=5&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=zlcBCZODtaNGPg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgX_n_TrAkzTm_bSHNj5_jov4uqiKhOTYfaSJx0iZD10oCIQDcfTvzMYdOtTPCPuW7jDuKhNu5Ufe7MvtUrrFa0wbGCQ%3D%3D&sig=AJfQdSswRAIgCRiX3QQSy6H4ao6k-W0lKoX9a5LsPmMstOoJasT86DwCIAdeXnPso_XL3PCNsATmJFhWRTP076QoPYyCjCfameWZ&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube" May 31 12:54:43 jeanremyvolumio volumio[29740]: error: updateQueue error: null May 31 12:54:43 jeanremyvolumio volumio[29740]: info: ------------------------------ 6ms May 31 12:54:43 jeanremyvolumio volumio[29740]: info: May 31 12:54:43 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:43 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:43 jeanremyvolumio volumio[29740]: info: sendMpdCommand addid "https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710483&ei=8t86aPupOqeM6dsP8rjvkAY&ip=176.147.19.217&id=o-AJ55qoHtTluH7a1jwHXvHcfiALaHrMi8St3jTuU0ruEA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688882%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25glenlr&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLMm6EDQgEevTApKw31ez3bcX8AiZR_D7gfHSCpMc0hyUG4nOFPbfd7mgtLQ5eeOSA7Row&spc=l3OVKcAaZ17PP3MP2UNwnUFyYFXV7-bo2hZe8u5Haq_VHSI9ZQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gKN10WCPXyX8r-1meKRLWnIQ&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=5&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=zlcBCZODtaNGPg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgX_n_TrAkzTm_bSHNj5_jov4uqiKhOTYfaSJx0iZD10oCIQDcfTvzMYdOtTPCPuW7jDuKhNu5Ufe7MvtUrrFa0wbGCQ%3D%3D&sig=AJfQdSswRAIgCRiX3QQSy6H4ao6k-W0lKoX9a5LsPmMstOoJasT86DwCIAdeXnPso_XL3PCNsATmJFhWRTP076QoPYyCjCfameWZ&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube" took 7 milliseconds May 31 12:54:43 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:43 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:43 jeanremyvolumio volumio[29740]: verbose: MPD COMMAND [object Object] May 31 12:54:43 jeanremyvolumio volumio[29740]: info: May 31 12:54:43 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:43 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:43 jeanremyvolumio volumio[29740]: info: ------------------------------ 7ms May 31 12:54:43 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 12:54:43 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand play May 31 12:54:43 jeanremyvolumio volumio[29740]: info: ------------------------------ 7ms May 31 12:54:43 jeanremyvolumio volumio[29740]: info: sendMpdCommand play took 5 milliseconds May 31 12:54:45 jeanremyvolumio volumio[29740]: info: May 31 12:54:45 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:45 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - ---- read samplerate, raw: May 31 12:54:45 jeanremyvolumio volumio[29740]: error: FusionDsp - invalid sample rate May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - ---- read samplerate from file: 48000 May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 31 12:54:45 jeanremyvolumio volumio[29740]: info: May 31 12:54:45 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:45 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 27 milliseconds May 31 12:54:45 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:54:45 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 6 milliseconds May 31 12:54:45 jeanremyvolumio volumio[29740]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseTrackInfo May 31 12:54:45 jeanremyvolumio volumio[29740]: info: ControllerMpd::pushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::servicePushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Apashe & Vladimir Cauchemar - Bellatores (Official Music Video)","artist":"vladimir cauchemar","album":"YouTube","uri":"https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710483&ei=8t86aPupOqeM6dsP8rjvkAY&ip=176.147.19.217&id=o-AJ55qoHtTluH7a1jwHXvHcfiALaHrMi8St3jTuU0ruEA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688882%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25glenlr&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLMm6EDQgEevTApKw31ez3bcX8AiZR_D7gfHSCpMc0hyUG4nOFPbfd7mgtLQ5eeOSA7Row&spc=l3OVKcAaZ17PP3MP2UNwnUFyYFXV7-bo2hZe8u5Haq_VHSI9ZQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gKN10WCPXyX8r-1meKRLWnIQ&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=5&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=zlcBCZODtaNGPg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgX_n_TrAkzTm_bSHNj5_jov4uqiKhOTYfaSJx0iZD10oCIQDcfTvzMYdOtTPCPuW7jDuKhNu5Ufe7MvtUrrFa0wbGCQ%3D%3D&sig=AJfQdSswRAIgCRiX3QQSy6H4ao6k-W0lKoX9a5LsPmMstOoJasT86DwCIAdeXnPso_XL3PCNsATmJFhWRTP076QoPYyCjCfameWZ&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: CURRENT POSITION 0 May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState stateService play May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState currentStatus stop May 31 12:54:45 jeanremyvolumio volumio[29740]: info: ------------------------------ 53ms May 31 12:54:45 jeanremyvolumio volumio[29740]: info: sendMpdCommand playlistinfo took 14 milliseconds May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseTrackInfo May 31 12:54:45 jeanremyvolumio volumio[29740]: info: ControllerMpd::pushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::servicePushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Apashe & Vladimir Cauchemar - Bellatores (Official Music Video)","artist":"vladimir cauchemar","album":"YouTube","uri":"https://rr2---sn-cv0tb0xn-apae.googlevideo.com/videoplayback?expire=1748710483&ei=8t86aPupOqeM6dsP8rjvkAY&ip=176.147.19.217&id=o-AJ55qoHtTluH7a1jwHXvHcfiALaHrMi8St3jTuU0ruEA&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748688882%2C&mh=7A&mm=31%2C29&mn=sn-cv0tb0xn-apae%2Csn-25glenlr&ms=au%2Crdu&mv=m&mvi=2&pl=20&rms=au%2Cau&gcr=fr&initcwndbps=3365000&siu=1&bui=AY1jyLMm6EDQgEevTApKw31ez3bcX8AiZR_D7gfHSCpMc0hyUG4nOFPbfd7mgtLQ5eeOSA7Row&spc=l3OVKcAaZ17PP3MP2UNwnUFyYFXV7-bo2hZe8u5Haq_VHSI9ZQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=gKN10WCPXyX8r-1meKRLWnIQ&rqh=1&gir=yes&clen=2170362&dur=135.021&lmt=1738507968669714&mt=1748688527&fvip=5&keepalive=yes&fexp=51355912%2C51469039&c=WEB&sefc=1&txp=4532534&n=zlcBCZODtaNGPg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgX_n_TrAkzTm_bSHNj5_jov4uqiKhOTYfaSJx0iZD10oCIQDcfTvzMYdOtTPCPuW7jDuKhNu5Ufe7MvtUrrFa0wbGCQ%3D%3D&sig=AJfQdSswRAIgCRiX3QQSy6H4ao6k-W0lKoX9a5LsPmMstOoJasT86DwCIAdeXnPso_XL3PCNsATmJFhWRTP076QoPYyCjCfameWZ&pot=Ml8FUDmiwg5QRwVse96gAt8MIR9qGs-33pbXb8R46tJAQqTod_j4LQcHowdkWm2GVn1uEGtEhJhT9JlwVhclTeZAWuI0C5BVnaPqxbyNB3eAw_HF53Ye_Z7wl1ZidMJWmQ%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 31 12:54:45 jeanremyvolumio volumio[29740]: verbose: CURRENT POSITION 0 May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState stateService play May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState currentStatus play May 31 12:54:45 jeanremyvolumio volumio[29740]: info: Received an update from plugin. extracting info from payload May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:45 jeanremyvolumio volumio[29740]: info: ------------------------------ 68ms May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is playing May 31 12:54:45 jeanremyvolumio volumio[29740]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is playing May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipping Monitor started May 31 12:54:45 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipping Monitor started May 31 12:54:48 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioNext May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreStateMachine::next May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 12:54:49 jeanremyvolumio volumio[29740]: info: ControllerMpd::next May 31 12:54:49 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand next May 31 12:54:49 jeanremyvolumio volumio[29740]: info: May 31 12:54:49 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces system playlist update May 31 12:54:49 jeanremyvolumio volumio[29740]: info: Ignoring MPD Status Update May 31 12:54:49 jeanremyvolumio volumio[29740]: info: May 31 12:54:49 jeanremyvolumio volumio[29740]: ---------------------------- MPD announces state update: player May 31 12:54:49 jeanremyvolumio volumio[29740]: info: sendMpdCommand next took 36 milliseconds May 31 12:54:49 jeanremyvolumio volumio[29740]: info: ControllerMpd::getState May 31 12:54:49 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::sendMpdCommand status May 31 12:54:49 jeanremyvolumio volumio[29740]: error: updateQueue error: null May 31 12:54:49 jeanremyvolumio volumio[29740]: info: ------------------------------ 8ms May 31 12:54:49 jeanremyvolumio volumio[29740]: info: sendMpdCommand status took 10 milliseconds May 31 12:54:49 jeanremyvolumio volumio[29740]: verbose: ControllerMpd::parseState May 31 12:54:49 jeanremyvolumio volumio[29740]: info: ControllerMpd::pushState May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::servicePushState May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreStateMachine::pushState May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CorePlayQueue::getTrack 0 May 31 12:54:49 jeanremyvolumio volumio[29740]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 12:54:49 jeanremyvolumio volumio[29740]: verbose: CURRENT POSITION 0 May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState stateService stop May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreStateMachine::syncState currentStatus play May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::volumioPushState May 31 12:54:49 jeanremyvolumio volumio[29740]: info: CoreStateMachine::stPlaybackTimer May 31 12:54:49 jeanremyvolumio volumio[29740]: info: ------------------------------ 49ms May 31 12:54:49 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is playing May 31 12:54:49 jeanremyvolumio volumio[29740]: info: FusionDsp - Volumio is not playing May 31 12:54:49 jeanremyvolumio volumio[29740]: info: FusionDsp - Clipped samples monitor stopped May 31 12:55:09 jeanremyvolumio volumio[29740]: Searching all installed plugins May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: mixcloud , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: CoreCommandRouter::executeOnPlugin: podcast , search May 31 12:55:09 jeanremyvolumio volumio[29740]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin podcast May 31 12:55:10 jeanremyvolumio volumio[29740]: Use XMLElement.setAttribute instead May 31 12:55:10 jeanremyvolumio volumio[29740]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:55:10 jeanremyvolumio volumio[29740]: TypeError: children[i].attr(...).value is not a function May 31 12:55:10 jeanremyvolumio volumio[29740]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 31 12:55:10 jeanremyvolumio volumio[29740]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 12:55:10 jeanremyvolumio volumio[29740]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:55:12 jeanremyvolumio sudo[28188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 12:54' May 31 12:55:12 jeanremyvolumio sudo[28188]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"