-- Logs begin at Thu 2019-02-14 19:11:59 KST, end at Sat 2024-04-27 16:02:48 KST. -- Apr 27 16:01:16 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioGetState Apr 27 16:01:26 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Apr 27 16:01:26 volumiogunpo volumio[846]: info: CoreStateMachine::getQueue Apr 27 16:01:26 volumiogunpo volumio[846]: info: CorePlayQueue::getQueue Apr 27 16:01:26 volumiogunpo volumio[846]: info: Saving local item undefined Apr 27 16:01:30 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioGetState Apr 27 16:01:40 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Apr 27 16:01:40 volumiogunpo volumio[846]: info: [youtube2-browse] browseUri: youtube2 Apr 27 16:01:42 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:42 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:42 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:42 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:44 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Apr 27 16:01:44 volumiogunpo volumio[846]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEhistory%22%7D%7D Apr 27 16:01:46 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:46 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:46 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:46 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:46 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:53 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::ClearQueue Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::stop Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::stPlaybackTimer Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::updateTrackBlock Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrackBlock Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::serviceStop Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::serviceStop Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 16:01:53 volumiogunpo volumio[846]: info: ControllerMpd::stop Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::clearPlayQueue Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::saveQueue Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushQueue Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::addQueueItems Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::addQueueItems Apr 27 16:01:53 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:01:53 volumiogunpo volumio[846]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%EB%9D%BC%ED%91%BC%EC%A0%A4(%E3%83%A9%E3%83%97%E3%83%B3%E3%83%84%E3%82%A7%E3%83%AB)%20%2F%20%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20Cover%22%2C%22artist%22%3A%22%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20SHIRAYUKI%20HINA%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FNcXfMM_TL3A%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLACFsGLBlcBUf7ODxi65t5rRaj-mw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22NcXfMM_TL3A%22%7D%7D%7D Apr 27 16:01:53 volumiogunpo volumio[846]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%EB%9D%BC%ED%91%BC%EC%A0%A4(%E3%83%A9%E3%83%97%E3%83%B3%E3%83%84%E3%82%A7%E3%83%AB)%20%2F%20%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20Cover%22%2C%22artist%22%3A%22%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20SHIRAYUKI%20HINA%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FNcXfMM_TL3A%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLACFsGLBlcBUf7ODxi65t5rRaj-mw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22NcXfMM_TL3A%22%7D%7D%7D Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushQueue Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::saveQueue Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::updateTrackBlock Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrackBlock Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPlay Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::play index 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::stop Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::play index undefined Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::startPlaybackTimer Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22NcXfMM_TL3A%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%EB%9D%BC%ED%91%BC%EC%A0%A4(%E3%83%A9%E3%83%97%E3%83%B3%E3%83%84%E3%82%A7%E3%83%AB)%20%2F%20%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20Cover%22%2C%22artist%22%3A%22%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20SHIRAYUKI%20HINA%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FNcXfMM_TL3A%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLACFsGLBlcBUf7ODxi65t5rRaj-mw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22NcXfMM_TL3A%22%7D%7D%7D Apr 27 16:01:53 volumiogunpo volumio[846]: info: Apr 27 16:01:53 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:53 volumiogunpo volumio[846]: info: sendMpdCommand stop took 39 milliseconds Apr 27 16:01:53 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:53 volumiogunpo volumio[846]: info: Apr 27 16:01:53 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:53 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:53 volumiogunpo volumio[846]: info: Apr 27 16:01:53 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:53 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:53 volumiogunpo volumio[846]: info: sendMpdCommand status took 7 milliseconds Apr 27 16:01:53 volumiogunpo volumio[846]: info: sendMpdCommand status took 5 milliseconds Apr 27 16:01:53 volumiogunpo volumio[846]: info: sendMpdCommand status took 5 milliseconds Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:53 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 27 16:01:53 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 27 16:01:53 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:53 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:53 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Apr 27 16:01:53 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Apr 27 16:01:53 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:53 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:53 volumiogunpo volumio[846]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Apr 27 16:01:53 volumiogunpo volumio[846]: info: ------------------------------ 36ms Apr 27 16:01:53 volumiogunpo volumio[846]: info: ------------------------------ 35ms Apr 27 16:01:53 volumiogunpo volumio[846]: info: ------------------------------ 35ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:54 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 16:01:54 volumiogunpo volumio[846]: info: sendMpdCommand stop took 9 milliseconds Apr 27 16:01:54 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: sendMpdCommand clear took 2 milliseconds Apr 27 16:01:54 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube" Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: error: updateQueue error: null Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 9ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: sendMpdCommand addid "https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube" took 8 milliseconds Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 8ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 6ms Apr 27 16:01:54 volumiogunpo volumio[846]: verbose: MPD COMMAND [object Object] Apr 27 16:01:54 volumiogunpo volumio[846]: verbose: MPD COMMAND [object Object] Apr 27 16:01:54 volumiogunpo volumio[846]: verbose: MPD COMMAND [object Object] Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 13ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 7ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 6ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 16:01:54 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand play Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Apr 27 16:01:54 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:01:54 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 9ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: sendMpdCommand play took 5 milliseconds Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 4ms Apr 27 16:01:54 volumiogunpo volumio[846]: info: ------------------------------ 2ms Apr 27 16:01:55 volumiogunpo volumio[846]: info: Apr 27 16:01:55 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:55 volumiogunpo volumio[846]: info: Apr 27 16:01:55 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand status took 2 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:55 volumiogunpo volumio[846]: info: Apr 27 16:01:55 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:55 volumiogunpo volumio[846]: info: Apr 27 16:01:55 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand status took 7 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand status took 4 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand status took 3 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus stop Apr 27 16:01:55 volumiogunpo volumio[846]: info: ------------------------------ 18ms Apr 27 16:01:55 volumiogunpo volumio[846]: info: Apr 27 16:01:55 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:55 volumiogunpo volumio[846]: info: Apr 27 16:01:55 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand status took 5 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand status took 3 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:01:55 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:01:55 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:01:55 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: ------------------------------ 53ms Apr 27 16:01:55 volumiogunpo volumio[846]: info: ------------------------------ 50ms Apr 27 16:01:55 volumiogunpo volumio[846]: info: ------------------------------ 50ms Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 33 milliseconds Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:01:55 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:01:55 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:01:55 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:01:55 volumiogunpo volumio[846]: info: ------------------------------ 64ms Apr 27 16:01:55 volumiogunpo volumio[846]: info: ------------------------------ 63ms Apr 27 16:02:01 volumiogunpo volumio[846]: Searching plugin music_service/youtube2 Apr 27 16:02:01 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 27 16:02:02 volumiogunpo volumio[846]: info: All search sources collected, pushing search results Apr 27 16:02:07 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Apr 27 16:02:07 volumiogunpo volumio[846]: info: [youtube2-browse] browseUri: youtube2/search@query=%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC1afpiIuBDcjYlmruAa0HiA%22%7D%7D Apr 27 16:02:08 volumiogunpo volumio[846]: InnertubeError: Something went wrong at ChannelHeaderLinksView! Apr 27 16:02:08 volumiogunpo volumio[846]: This is a bug, please report it at https://github.com/patrickkfkan/Volumio-YouTube.js/issues Apr 27 16:02:08 volumiogunpo volumio[846]: at Function._Parser_printError (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18226:16) Apr 27 16:02:08 volumiogunpo volumio[846]: at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18059:70) Apr 27 16:02:08 volumiogunpo volumio[846]: at new C4TabbedHeader (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:10383:42) Apr 27 16:02:08 volumiogunpo volumio[846]: at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24) Apr 27 16:02:08 volumiogunpo volumio[846]: at Function.parse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18096:39) Apr 27 16:02:08 volumiogunpo volumio[846]: at Function.parseResponse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:17920:39) Apr 27 16:02:08 volumiogunpo volumio[846]: at EndpointModel._EndpointModel_doGetContents (/data/plugins/music_service/youtube2/dist/lib/model/EndpointModel.js:39:49) Apr 27 16:02:08 volumiogunpo volumio[846]: at runMicrotasks () Apr 27 16:02:08 volumiogunpo volumio[846]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 27 16:02:08 volumiogunpo volumio[846]: at async GenericViewHandler.getContents (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/GenericViewHandler.js:44:26) Apr 27 16:02:08 volumiogunpo volumio[846]: at async GenericViewHandler.browse (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/FeedViewHandler.js:23:26) Apr 27 16:02:08 volumiogunpo volumio[846]: at async BrowseController.browseUri (/data/plugins/music_service/youtube2/dist/lib/controller/browse/index.js:35:20) { Apr 27 16:02:08 volumiogunpo volumio[846]: info: { Apr 27 16:02:08 volumiogunpo volumio[846]: stack: 'Error: Type mismatch, got ChannelHeaderLinksView but expected ChannelHeaderLinks\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18053:19)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at new C4TabbedHeader (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:10383:42)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at Function.parseItem (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at Function.parse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:18096:39)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at Function.parseResponse (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:17920:39)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at EndpointModel._EndpointModel_doGetContents (/data/plugins/music_service/youtube2/dist/lib/model/EndpointModel.js:39:49)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at runMicrotasks ()\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at processTicksAndRejections (internal/process/task_queues.js:93:5)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at async GenericViewHandler.getContents (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/GenericViewHandler.js:44:26)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at async GenericViewHandler.browse (/data/plugins/music_service/youtube2/dist/lib/controller/browse/view-handlers/FeedViewHandler.js:23:26)\n' + Apr 27 16:02:08 volumiogunpo volumio[846]: ' at async BrowseController.browseUri (/data/plugins/music_service/youtube2/dist/lib/controller/browse/index.js:35:20)' Apr 27 16:02:08 volumiogunpo volumio[846]: }, Apr 27 16:02:08 volumiogunpo volumio[846]: date: 2024-04-27T07:02:08.072Z, Apr 27 16:02:08 volumiogunpo volumio[846]: version: '0.3.3' Apr 27 16:02:08 volumiogunpo volumio[846]: } Apr 27 16:02:08 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:08 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:08 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:08 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:08 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:08 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:08 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:11 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::ClearQueue Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::stPlaybackTimer Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::updateTrackBlock Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::getTrackBlock Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::serviceStop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::serviceStop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 16:02:11 volumiogunpo volumio[846]: info: ControllerMpd::stop Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::clearPlayQueue Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::saveQueue Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushQueue Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::addQueueItems Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::addQueueItems Apr 27 16:02:11 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:11 volumiogunpo volumio[846]: info: Adding Item to queue: youtube2/search@query=%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC1afpiIuBDcjYlmruAa0HiA%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22gBWIv6NvLcU%22%2C%22playlistId%22%3A%22PLzdLDJsHzz2NiuwjyW6QgSck4PrwlSyOc%22%7D%7D Apr 27 16:02:11 volumiogunpo volumio[846]: info: Exploding uri youtube2/search@query=%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC1afpiIuBDcjYlmruAa0HiA%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22gBWIv6NvLcU%22%2C%22playlistId%22%3A%22PLzdLDJsHzz2NiuwjyW6QgSck4PrwlSyOc%22%7D%7D in service youtube2 Apr 27 16:02:11 volumiogunpo volumio[846]: info: [youtube2-browse] explodeUri: youtube2/search@query=%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22UC1afpiIuBDcjYlmruAa0HiA%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22gBWIv6NvLcU%22%2C%22playlistId%22%3A%22PLzdLDJsHzz2NiuwjyW6QgSck4PrwlSyOc%22%7D%7D Apr 27 16:02:11 volumiogunpo volumio[846]: info: Apr 27 16:02:11 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:11 volumiogunpo volumio[846]: info: sendMpdCommand stop took 42 milliseconds Apr 27 16:02:11 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:11 volumiogunpo volumio[846]: info: Apr 27 16:02:11 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:11 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:11 volumiogunpo volumio[846]: info: Apr 27 16:02:11 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:11 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:11 volumiogunpo volumio[846]: info: sendMpdCommand status took 13 milliseconds Apr 27 16:02:11 volumiogunpo volumio[846]: info: sendMpdCommand status took 12 milliseconds Apr 27 16:02:11 volumiogunpo volumio[846]: info: sendMpdCommand status took 10 milliseconds Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:11 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 27 16:02:11 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 27 16:02:11 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:11 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: No code Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: No code Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ラプンツェル(Rapunzel) / 白雪ひな Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr1---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222913&ei=4aIsZui1NMP52roPjbGlyA8&ip=182.218.226.51&id=o-AKciJcT2a94qi2r88SOx3CEAZ11lY-r3lx0hQMJorqck&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Hq&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-npoe7nsd&ms=au%2Conr&mv=m&mvi=1&pl=17&initcwndbps=2038750&siu=1&bui=AWRWj2TjaMvPPgnVGY_T4qRmXTqaKLMPCJoxbu81rWMjWUlZljqdldduUX43u-FMlIvF0ldt4A&spc=UWF9f2nrEPmzC-p176DZon2eczz78Pufy_4A6wrVb03E_fPORCPpmp3nZvFMbIg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Pp0JBpc3YBuVKx_PUYFwdAQQ&rqh=1&gir=yes&clen=4541257&dur=267.941&lmt=1711845196715374&mt=1714201031&fvip=1&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=mnQXB-TfrgnA6Q&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIhAJe_5dsjOSYgymrae3qKAg6m9Zpe41cilG2ihTIMUM5WAiA7wtiknW2J0vBUizxgMiO30GsWoTAIPTdJLfPby3ZuBw%3D%3D&sig=AJfQdSswRAIgN4IyEprvBp7hknT5i0f0ub18lLB72597rKmKKmDKl3sCIGTnFogEZ4URko8BFT9MUM5ltfbIlI_inCIEFkXl5eu0&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:11 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus stop Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: No code Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:11 volumiogunpo volumio[846]: info: ------------------------------ 120ms Apr 27 16:02:11 volumiogunpo volumio[846]: info: ------------------------------ 117ms Apr 27 16:02:11 volumiogunpo volumio[846]: info: ------------------------------ 118ms Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushQueue Apr 27 16:02:13 volumiogunpo volumio[846]: info: CorePlayQueue::saveQueue Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::updateTrackBlock Apr 27 16:02:13 volumiogunpo volumio[846]: info: CorePlayQueue::getTrackBlock Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPlay Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::play index 0 Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::stop Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::play index undefined Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 16:02:13 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:13 volumiogunpo volumio[846]: info: CoreStateMachine::startPlaybackTimer Apr 27 16:02:13 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:13 volumiogunpo volumio[846]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22gBWIv6NvLcU%22%2C%22playlistId%22%3A%22PLzdLDJsHzz2NiuwjyW6QgSck4PrwlSyOc%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%EB%82%99%ED%96%A5(%E9%83%BD%E8%90%BD%E3%81%A1)%20%2F%20%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20Cover%22%2C%22artist%22%3A%22%EC%8B%9C%EB%9D%BC%EC%9C%A0%ED%82%A4%20%ED%9E%88%EB%82%98%20SHIRAYUKI%20HINA%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FgBWIv6NvLcU%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBaclG2jOF4jpxdE38oyWNXCqH5uA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22gBWIv6NvLcU%22%2C%22playlistId%22%3A%22PLzdLDJsHzz2NiuwjyW6QgSck4PrwlSyOc%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Apr 27 16:02:14 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand stop took 8 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand clear Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand clear took 2 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube" Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: error: updateQueue error: null Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 10ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand addid "https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube" took 9 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 7ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 5ms Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: MPD COMMAND [object Object] Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: MPD COMMAND [object Object] Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: MPD COMMAND [object Object] Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 12ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 7ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 5ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand play Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces system playlist update Apr 27 16:02:14 volumiogunpo volumio[846]: info: Ignoring MPD Status Update Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 11ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand play took 9 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 9ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 6ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand status took 8 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand status took 7 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand status took 4 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:14 volumiogunpo volumio[846]: info: Apr 27 16:02:14 volumiogunpo volumio[846]: ---------------------------- MPD announces state update: player Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::getState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand status Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand status took 14 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand status took 7 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"낙향(都落ち) / 시라유키 히나 Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus stop Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"낙향(都落ち) / 시라유키 히나 Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:02:14 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"낙향(都落ち) / 시라유키 히나 Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:02:14 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 59ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 61ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 60ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand status took 45 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 39 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseState Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"낙향(都落ち) / 시라유키 히나 Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:02:14 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"낙향(都落ち) / 시라유키 히나 Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:02:14 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 85ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 77ms Apr 27 16:02:14 volumiogunpo volumio[846]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: ControllerMpd::parseTrackInfo Apr 27 16:02:14 volumiogunpo volumio[846]: info: ControllerMpd::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::servicePushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CorePlayQueue::getTrack 0 Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"낙향(都落ち) / 시라유키 히나 Cover","artist":"시라유키 히나 SHIRAYUKI HINA","album":"YouTube","uri":"https://rr4---sn-ab02a0nfpgxapox-bh2zr.googlevideo.com/videoplayback?expire=1714222933&ei=9aIsZpCWIKrYvcAP-_eRmA8&ip=182.218.226.51&id=o-AOeDDagJoAEqln2rj3FYRvzXtiyJODg7X4Tkd0TmgEYN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nk&mm=31%2C26&mn=sn-ab02a0nfpgxapox-bh2zr%2Csn-oguelnzs&ms=au%2Conr&mv=m&mvi=4&pl=17&gcr=kr&initcwndbps=2038750&siu=1&bui=AWRWj2SpB5AwUTUWFVNArni4J9ho0peNlhLUBeVdyp7j_mS_V39Ny9Wn7yzLtiybPNC6-wzqVA&spc=UWF9f8OOVl4X3Zz1jIgm9v44YPZ-chWqwItHweoDTNdTUOFU_IUQNdMo6ngM4pw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=pTKol2DDWLtke0DnANr-zHsQ&rqh=1&gir=yes&clen=4536415&dur=250.441&lmt=1686279862438715&mt=1714201031&fvip=2&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=NBCShUgZEHVPRA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRQIgYql4dsjZQOTGIuZ5zHmvol4s0JqbTHk0zWtcgzdJ3wMCIQDWcKbGFMZs7stYQqiBkuXPSD5yNNqOH_M4-0yJLYRAhQ%3D%3D&sig=AJfQdSswRgIhAPnPiKBTg0_hp0wHS7FxUrczpBiXukD--EsQsdGYsKOAAiEA8lvYOSM9V1fh9hDtMCaKjTWImh_fkmdx1P-NvYd0pTo%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 27 16:02:14 volumiogunpo volumio[846]: verbose: CURRENT POSITION 0 Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState stateService play Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::syncState currentStatus play Apr 27 16:02:14 volumiogunpo volumio[846]: info: Received an update from plugin. extracting info from payload Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreStateMachine::pushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioPushState Apr 27 16:02:14 volumiogunpo volumio[846]: info: ------------------------------ 86ms Apr 27 16:02:16 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioGetState Apr 27 16:02:20 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Apr 27 16:02:20 volumiogunpo volumio[846]: info: CoreStateMachine::getQueue Apr 27 16:02:20 volumiogunpo volumio[846]: info: CorePlayQueue::getQueue Apr 27 16:02:20 volumiogunpo volumio[846]: info: Saving local item undefined Apr 27 16:02:22 volumiogunpo volumio[846]: info: CoreCommandRouter::volumioGetState Apr 27 16:02:39 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 16:02:40 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:42 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 16:02:42 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:45 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 16:02:45 volumiogunpo volumio[846]: info: Preload queue cleared Apr 27 16:02:47 volumiogunpo volumio[846]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 16:02:47 volumiogunpo volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 16:02:47 volumiogunpo volumio[846]: TypeError: Cannot read property 'length' of undefined Apr 27 16:02:47 volumiogunpo volumio[846]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 27 16:02:47 volumiogunpo volumio[846]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 27 16:02:47 volumiogunpo volumio[846]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 27 16:02:47 volumiogunpo volumio[846]: at Parser.emit (events.js:315:20) Apr 27 16:02:47 volumiogunpo volumio[846]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 27 16:02:47 volumiogunpo volumio[846]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 27 16:02:47 volumiogunpo volumio[846]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 27 16:02:47 volumiogunpo volumio[846]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 27 16:02:47 volumiogunpo volumio[846]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 27 16:02:47 volumiogunpo volumio[846]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 27 16:02:47 volumiogunpo volumio[846]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 27 16:02:47 volumiogunpo volumio[846]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 27 16:02:47 volumiogunpo volumio[846]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 27 16:02:47 volumiogunpo volumio[846]: at IncomingMessage.emit (events.js:327:22) Apr 27 16:02:47 volumiogunpo volumio[846]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 27 16:02:47 volumiogunpo volumio[846]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 27 16:02:47 volumiogunpo volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 16:02:48 volumiogunpo sudo[7201]: volumio : unable to resolve host volumiogunpo Apr 27 16:02:48 volumiogunpo sudo[7201]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 27 16:02:48 volumiogunpo sudo[7201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 16:01 Apr 27 16:02:48 volumiogunpo sudo[7201]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"