-- Logs begin at Sun 2024-04-21 08:27:08 +07, end at Sun 2024-04-21 08:52:55 +07. -- Apr 21 08:51:14 volumio volumio[578]: info: CoreCommandRouter::volumioGetQueue Apr 21 08:51:14 volumio volumio[578]: info: CoreStateMachine::getQueue Apr 21 08:51:14 volumio volumio[578]: info: CorePlayQueue::getQueue Apr 21 08:51:16 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Apr 21 08:51:16 volumio volumio[578]: info: [youtube2-browse] browseUri: youtube2 Apr 21 08:51:16 volumio volumio[578]: info: [youtube2] InnertubeLoader: creating Innertube instance... Apr 21 08:51:23 volumio ntpd[414]: Soliciting pool server 2001:da8:9000::130 Apr 21 08:51:26 volumio volumio[578]: info: [youtube2] InnertubeLoader: creating Auth instance... Apr 21 08:51:26 volumio volumio[578]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 08:51:32 volumio volumio[578]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 08:51:32 volumio volumio[578]: info: [youtube2] InnertubeLoader: Auth instance created (status: pending sign-in) Apr 21 08:51:33 volumio volumio[578]: info: Preload queue cleared Apr 21 08:51:33 volumio volumio[578]: info: Preload queue cleared Apr 21 08:51:33 volumio volumio[578]: info: Preload queue cleared Apr 21 08:51:33 volumio volumio[578]: info: Preload queue cleared Apr 21 08:51:34 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Apr 21 08:51:34 volumio volumio[578]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEwhat_to_watch%22%7D%7D Apr 21 08:51:35 volumio volumio[578]: FeedNudge not found! Apr 21 08:51:35 volumio volumio[578]: This is a bug, want to help us fix it? Follow the instructions at https://github.com/patrickkfkan/Volumio-YouTube.js/blob/main/docs/updating-the-parser.md or report it at https://github.com/patrickkfkan/Volumio-YouTube.js/issues! Apr 21 08:51:35 volumio volumio[578]: Introspected and JIT generated this class in the meantime: Apr 21 08:51:35 volumio volumio[578]: class FeedNudge extends YTNode { Apr 21 08:51:35 volumio volumio[578]: static type = 'FeedNudge'; Apr 21 08:51:35 volumio volumio[578]: title: Text; Apr 21 08:51:35 volumio volumio[578]: subtitle: Text; Apr 21 08:51:35 volumio volumio[578]: endpoint: NavigationEndpoint; Apr 21 08:51:35 volumio volumio[578]: apply_modernized_style: boolean; Apr 21 08:51:35 volumio volumio[578]: contents_location: string; Apr 21 08:51:35 volumio volumio[578]: trim_style: string; Apr 21 08:51:35 volumio volumio[578]: background_style: string; Apr 21 08:51:35 volumio volumio[578]: disable_drop_shadow: boolean; Apr 21 08:51:35 volumio volumio[578]: constructor(data: RawNode) { Apr 21 08:51:35 volumio volumio[578]: super(); Apr 21 08:51:35 volumio volumio[578]: this.title = new Text(data.title); Apr 21 08:51:35 volumio volumio[578]: this.subtitle = new Text(data.subtitle); Apr 21 08:51:35 volumio volumio[578]: this.endpoint = new NavigationEndpoint(data.impressionEndpoint); Apr 21 08:51:35 volumio volumio[578]: this.apply_modernized_style = data.applyModernizedStyle; Apr 21 08:51:35 volumio volumio[578]: this.contents_location = data.contentsLocation; Apr 21 08:51:35 volumio volumio[578]: this.trim_style = data.trimStyle; Apr 21 08:51:35 volumio volumio[578]: this.background_style = data.backgroundStyle; Apr 21 08:51:35 volumio volumio[578]: this.disable_drop_shadow = data.disableDropShadow; Apr 21 08:51:35 volumio volumio[578]: } Apr 21 08:51:35 volumio volumio[578]: } Apr 21 08:51:35 volumio volumio[578]: info: Preload queue cleared Apr 21 08:51:39 volumio volumio[578]: Searching plugin music_service/youtube2 Apr 21 08:51:39 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 08:51:41 volumio volumio[578]: SearchHeader not found! Apr 21 08:51:41 volumio volumio[578]: This is a bug, want to help us fix it? Follow the instructions at https://github.com/patrickkfkan/Volumio-YouTube.js/blob/main/docs/updating-the-parser.md or report it at https://github.com/patrickkfkan/Volumio-YouTube.js/issues! Apr 21 08:51:41 volumio volumio[578]: Introspected and JIT generated this class in the meantime: Apr 21 08:51:41 volumio volumio[578]: class SearchHeader extends YTNode { Apr 21 08:51:41 volumio volumio[578]: static type = 'SearchHeader'; Apr 21 08:51:41 volumio volumio[578]: chip_bar: YTNodes.ChipCloud | null; Apr 21 08:51:41 volumio volumio[578]: search_filter_button: YTNodes.Button | null; Apr 21 08:51:41 volumio volumio[578]: constructor(data: RawNode) { Apr 21 08:51:41 volumio volumio[578]: super(); Apr 21 08:51:41 volumio volumio[578]: this.chip_bar = Parser.parseItem(data.chipBar, [ YTNodes.ChipCloud ]); Apr 21 08:51:41 volumio volumio[578]: this.search_filter_button = Parser.parseItem(data.searchFilterButton, [ YTNodes.Button ]); Apr 21 08:51:41 volumio volumio[578]: } Apr 21 08:51:41 volumio volumio[578]: } Apr 21 08:51:42 volumio volumio[578]: info: All search sources collected, pushing search results Apr 21 08:51:42 volumio volumio[578]: Searching plugin music_service/youtube2 Apr 21 08:51:42 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 08:51:43 volumio volumio[578]: Searching plugin music_service/youtube2 Apr 21 08:51:43 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 08:51:45 volumio volumio[578]: info: All search sources collected, pushing search results Apr 21 08:51:45 volumio volumio[578]: Searching plugin music_service/youtube2 Apr 21 08:51:45 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Apr 21 08:51:48 volumio volumio[578]: info: All search sources collected, pushing search results Apr 21 08:51:50 volumio volumio[578]: info: All search sources collected, pushing search results Apr 21 08:51:55 volumio volumio[578]: info: Preload queue cleared Apr 21 08:51:55 volumio volumio[578]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::ClearQueue Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::stop Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 08:51:55 volumio volumio[578]: info: CorePlayQueue::clearPlayQueue Apr 21 08:51:55 volumio volumio[578]: info: CorePlayQueue::saveQueue Apr 21 08:51:55 volumio volumio[578]: info: CoreCommandRouter::volumioPushQueue Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::addQueueItems Apr 21 08:51:55 volumio volumio[578]: info: CorePlayQueue::addQueueItems Apr 21 08:51:55 volumio volumio[578]: info: Preload queue cleared Apr 21 08:51:55 volumio volumio[578]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20guitar%20l%C3%A3ng%20m%E1%BA%A1n%20%E2%9D%A4%20Nh%E1%BB%AFng%20giai%20%C4%91i%E1%BB%87u%20guitar%20hay%20nh%E1%BA%A5t%20cho%20nh%E1%BB%AFng%20kho%E1%BA%A3nh%20kh%E1%BA%AFc%20l%C3%A3ng%20m%E1%BA%A1n%20nh%E1%BA%A5t%20c%E1%BB%A7a%20b%E1%BA%A1n%22%2C%22artist%22%3A%22Romantic%20Guitar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Firz1XRFZA0Q%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDSdf9g4GIbuO7YdDWugiw2CZc2mQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22irz1XRFZA0Q%22%2C%22params%22%3A%22qgMOTmhhYyBraG9uZyBsb2m6AwsIkbqz-t_dnrPpAboDCwi6wI6m-9y1yt4BugMKCNapmeOGvuLpVboDCwi9v6eo3b7E_uABugMLCMfEjYfx0-zR1wG6AwoIho-eofzm6dFSugMLCN_C3-HBtYrtjQG6AwsIktKDsorLoLmsAboDCwj079i8ptD64KABugMKCLjo6sC7s9DuGboDCwi10dDxs_qd99gBugMLCK7LuMb_kpmT9QG6AwoI1KnhzfTTuaBAugMKCLbyj7-CobHqCroDCgja_Lqi2bWksji6AxwSGlJERU12LWFBaWMtdVNRMk4wajlkWjAyWWlBugMKCLKBuZ2_jYmOGroDCwjg5eWxtpLqv6wB8gMFDXftSD8%253D%22%7D%7D%7D Apr 21 08:51:55 volumio volumio[578]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20guitar%20l%C3%A3ng%20m%E1%BA%A1n%20%E2%9D%A4%20Nh%E1%BB%AFng%20giai%20%C4%91i%E1%BB%87u%20guitar%20hay%20nh%E1%BA%A5t%20cho%20nh%E1%BB%AFng%20kho%E1%BA%A3nh%20kh%E1%BA%AFc%20l%C3%A3ng%20m%E1%BA%A1n%20nh%E1%BA%A5t%20c%E1%BB%A7a%20b%E1%BA%A1n%22%2C%22artist%22%3A%22Romantic%20Guitar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Firz1XRFZA0Q%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDSdf9g4GIbuO7YdDWugiw2CZc2mQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22irz1XRFZA0Q%22%2C%22params%22%3A%22qgMOTmhhYyBraG9uZyBsb2m6AwsIkbqz-t_dnrPpAboDCwi6wI6m-9y1yt4BugMKCNapmeOGvuLpVboDCwi9v6eo3b7E_uABugMLCMfEjYfx0-zR1wG6AwoIho-eofzm6dFSugMLCN_C3-HBtYrtjQG6AwsIktKDsorLoLmsAboDCwj079i8ptD64KABugMKCLjo6sC7s9DuGboDCwi10dDxs_qd99gBugMLCK7LuMb_kpmT9QG6AwoI1KnhzfTTuaBAugMKCLbyj7-CobHqCroDCgja_Lqi2bWksji6AxwSGlJERU12LWFBaWMtdVNRMk4wajlkWjAyWWlBugMKCLKBuZ2_jYmOGroDCwjg5eWxtpLqv6wB8gMFDXftSD8%253D%22%7D%7D%7D in service youtube2 Apr 21 08:51:55 volumio volumio[578]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20guitar%20l%C3%A3ng%20m%E1%BA%A1n%20%E2%9D%A4%20Nh%E1%BB%AFng%20giai%20%C4%91i%E1%BB%87u%20guitar%20hay%20nh%E1%BA%A5t%20cho%20nh%E1%BB%AFng%20kho%E1%BA%A3nh%20kh%E1%BA%AFc%20l%C3%A3ng%20m%E1%BA%A1n%20nh%E1%BA%A5t%20c%E1%BB%A7a%20b%E1%BA%A1n%22%2C%22artist%22%3A%22Romantic%20Guitar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Firz1XRFZA0Q%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDSdf9g4GIbuO7YdDWugiw2CZc2mQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22irz1XRFZA0Q%22%2C%22params%22%3A%22qgMOTmhhYyBraG9uZyBsb2m6AwsIkbqz-t_dnrPpAboDCwi6wI6m-9y1yt4BugMKCNapmeOGvuLpVboDCwi9v6eo3b7E_uABugMLCMfEjYfx0-zR1wG6AwoIho-eofzm6dFSugMLCN_C3-HBtYrtjQG6AwsIktKDsorLoLmsAboDCwj079i8ptD64KABugMKCLjo6sC7s9DuGboDCwi10dDxs_qd99gBugMLCK7LuMb_kpmT9QG6AwoI1KnhzfTTuaBAugMKCLbyj7-CobHqCroDCgja_Lqi2bWksji6AxwSGlJERU12LWFBaWMtdVNRMk4wajlkWjAyWWlBugMKCLKBuZ2_jYmOGroDCwjg5eWxtpLqv6wB8gMFDXftSD8%253D%22%7D%7D%7D Apr 21 08:51:55 volumio volumio[578]: info: CoreCommandRouter::volumioPushQueue Apr 21 08:51:55 volumio volumio[578]: info: CorePlayQueue::saveQueue Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::updateTrackBlock Apr 21 08:51:55 volumio volumio[578]: info: CorePlayQueue::getTrackBlock Apr 21 08:51:55 volumio volumio[578]: info: CoreCommandRouter::volumioPlay Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::play index 0 Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::stop Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::play index undefined Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 08:51:55 volumio volumio[578]: info: CorePlayQueue::getTrack 0 Apr 21 08:51:55 volumio volumio[578]: info: CoreStateMachine::startPlaybackTimer Apr 21 08:51:55 volumio volumio[578]: info: CorePlayQueue::getTrack 0 Apr 21 08:51:55 volumio volumio[578]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22irz1XRFZA0Q%22%2C%22params%22%3A%22qgMOTmhhYyBraG9uZyBsb2m6AwsIkbqz-t_dnrPpAboDCwi6wI6m-9y1yt4BugMKCNapmeOGvuLpVboDCwi9v6eo3b7E_uABugMLCMfEjYfx0-zR1wG6AwoIho-eofzm6dFSugMLCN_C3-HBtYrtjQG6AwsIktKDsorLoLmsAboDCwj079i8ptD64KABugMKCLjo6sC7s9DuGboDCwi10dDxs_qd99gBugMLCK7LuMb_kpmT9QG6AwoI1KnhzfTTuaBAugMKCLbyj7-CobHqCroDCgja_Lqi2bWksji6AxwSGlJERU12LWFBaWMtdVNRMk4wajlkWjAyWWlBugMKCLKBuZ2_jYmOGroDCwjg5eWxtpLqv6wB8gMFDXftSD8%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20guitar%20l%C3%A3ng%20m%E1%BA%A1n%20%E2%9D%A4%20Nh%E1%BB%AFng%20giai%20%C4%91i%E1%BB%87u%20guitar%20hay%20nh%E1%BA%A5t%20cho%20nh%E1%BB%AFng%20kho%E1%BA%A3nh%20kh%E1%BA%AFc%20l%C3%A3ng%20m%E1%BA%A1n%20nh%E1%BA%A5t%20c%E1%BB%A7a%20b%E1%BA%A1n%22%2C%22artist%22%3A%22Romantic%20Guitar%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Firz1XRFZA0Q%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLDSdf9g4GIbuO7YdDWugiw2CZc2mQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22irz1XRFZA0Q%22%2C%22params%22%3A%22qgMOTmhhYyBraG9uZyBsb2m6AwsIkbqz-t_dnrPpAboDCwi6wI6m-9y1yt4BugMKCNapmeOGvuLpVboDCwi9v6eo3b7E_uABugMLCMfEjYfx0-zR1wG6AwoIho-eofzm6dFSugMLCN_C3-HBtYrtjQG6AwsIktKDsorLoLmsAboDCwj079i8ptD64KABugMKCLjo6sC7s9DuGboDCwi10dDxs_qd99gBugMLCK7LuMb_kpmT9QG6AwoI1KnhzfTTuaBAugMKCLbyj7-CobHqCroDCgja_Lqi2bWksji6AxwSGlJERU12LWFBaWMtdVNRMk4wajlkWjAyWWlBugMKCLKBuZ2_jYmOGroDCwjg5eWxtpLqv6wB8gMFDXftSD8%253D%22%7D%7D%7D Apr 21 08:51:58 volumio volumio[578]: info: CorePlayQueue::getTrack 0 Apr 21 08:51:58 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 08:51:59 volumio volumio[578]: info: sendMpdCommand stop took 135 milliseconds Apr 21 08:51:59 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 08:51:59 volumio volumio[578]: info: Apr 21 08:51:59 volumio volumio[578]: ---------------------------- MPD announces system playlist update Apr 21 08:51:59 volumio volumio[578]: info: Ignoring MPD Status Update Apr 21 08:51:59 volumio volumio[578]: info: sendMpdCommand clear took 48 milliseconds Apr 21 08:51:59 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-8pxuuxa-i5oel.googlevideo.com/videoplayback?expire=1713685915&ei=O3EkZorxL6ah7OsPwrGm8A4&ip=116.96.44.234&id=o-AAb9uUrfiAyUIahU0IAI_j0vefvGyqfoumuyLobRrz9g&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nD&mm=31%2C29&mn=sn-8pxuuxa-i5oel%2Csn-8pxuuxa-i5ozs&ms=au%2Crdu&mv=m&mvi=1&pcm2cms=yes&pl=23&initcwndbps=2191250&bui=AaUN6a0soEpZO8nzdisZIFSj2S9xFifPeCGjJ53R6gRSXzOaILT5qxi-5nb7hV5OI4HPgz2dZADtsiTC&spc=UWF9f0CUq1yclWCDGlW8noBDsJdmVWV5FhK4NcByn4b8kEN2Xg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fAyDfWAhyNAFReF_OkMWI2EQ&gir=yes&clen=865542155&dur=43152.901&lmt=1702862691176729&mt=1713663655&fvip=8&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=BzvStp8f0W8YWQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhANdJycfNlUA4Gc2Oq1Y-jwQXGdOgP0UP5hs2MKZafAQ6AiEAmlnTR4SgFsn8eIaFNk6zGdk6L6HDnyDWXCTlhqCJYko%3D&sig=AJfQdSswRQIhAJOdaA-9UjtM0cHIuFumE1t5xjeY67du9FAZy3LBntUsAiAK0o6aHPgwAUcDiXQ0uz-7jYw7qoCCmeW1KkqDHKsInA%3D%3D&cver=2.20230104.01.00&t.YouTube" Apr 21 08:51:59 volumio volumio[578]: error: updateQueue error: null Apr 21 08:51:59 volumio volumio[578]: info: Apr 21 08:51:59 volumio volumio[578]: ---------------------------- MPD announces system playlist update Apr 21 08:51:59 volumio volumio[578]: info: Ignoring MPD Status Update Apr 21 08:51:59 volumio volumio[578]: info: ------------------------------ 117ms Apr 21 08:51:59 volumio volumio[578]: info: sendMpdCommand addid "https://rr1---sn-8pxuuxa-i5oel.googlevideo.com/videoplayback?expire=1713685915&ei=O3EkZorxL6ah7OsPwrGm8A4&ip=116.96.44.234&id=o-AAb9uUrfiAyUIahU0IAI_j0vefvGyqfoumuyLobRrz9g&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nD&mm=31%2C29&mn=sn-8pxuuxa-i5oel%2Csn-8pxuuxa-i5ozs&ms=au%2Crdu&mv=m&mvi=1&pcm2cms=yes&pl=23&initcwndbps=2191250&bui=AaUN6a0soEpZO8nzdisZIFSj2S9xFifPeCGjJ53R6gRSXzOaILT5qxi-5nb7hV5OI4HPgz2dZADtsiTC&spc=UWF9f0CUq1yclWCDGlW8noBDsJdmVWV5FhK4NcByn4b8kEN2Xg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fAyDfWAhyNAFReF_OkMWI2EQ&gir=yes&clen=865542155&dur=43152.901&lmt=1702862691176729&mt=1713663655&fvip=8&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=BzvStp8f0W8YWQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhANdJycfNlUA4Gc2Oq1Y-jwQXGdOgP0UP5hs2MKZafAQ6AiEAmlnTR4SgFsn8eIaFNk6zGdk6L6HDnyDWXCTlhqCJYko%3D&sig=AJfQdSswRQIhAJOdaA-9UjtM0cHIuFumE1t5xjeY67du9FAZy3LBntUsAiAK0o6aHPgwAUcDiXQ0uz-7jYw7qoCCmeW1KkqDHKsInA%3D%3D&cver=2.20230104.01.00&t.YouTube" took 82 milliseconds Apr 21 08:51:59 volumio volumio[578]: verbose: MPD COMMAND [object Object] Apr 21 08:51:59 volumio volumio[578]: verbose: MPD COMMAND [object Object] Apr 21 08:51:59 volumio volumio[578]: verbose: MPD COMMAND [object Object] Apr 21 08:51:59 volumio volumio[578]: info: Apr 21 08:51:59 volumio volumio[578]: ---------------------------- MPD announces system playlist update Apr 21 08:51:59 volumio volumio[578]: info: Ignoring MPD Status Update Apr 21 08:51:59 volumio volumio[578]: info: ------------------------------ 160ms Apr 21 08:51:59 volumio volumio[578]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 08:51:59 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand play Apr 21 08:51:59 volumio volumio[578]: info: ------------------------------ 88ms Apr 21 08:51:59 volumio volumio[578]: info: sendMpdCommand play took 62 milliseconds Apr 21 08:52:02 volumio volumio[578]: info: Apr 21 08:52:02 volumio volumio[578]: ---------------------------- MPD announces state update: player Apr 21 08:52:02 volumio volumio[578]: info: ControllerMpd::getState Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand status Apr 21 08:52:03 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 21 08:52:03 volumio volumio[578]: info: Apr 21 08:52:03 volumio volumio[578]: ---------------------------- MPD announces state update: player Apr 21 08:52:03 volumio volumio[578]: info: ControllerMpd::getState Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand status Apr 21 08:52:03 volumio volumio[578]: info: sendMpdCommand status took 159 milliseconds Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::parseState Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 08:52:03 volumio volumio[578]: info: sendMpdCommand status took 106 milliseconds Apr 21 08:52:03 volumio volumio[578]: info: sendMpdCommand playlistinfo took 58 milliseconds Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::parseState Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::parseTrackInfo Apr 21 08:52:03 volumio volumio[578]: info: ControllerMpd::pushState Apr 21 08:52:03 volumio volumio[578]: info: CoreCommandRouter::servicePushState Apr 21 08:52:03 volumio volumio[578]: info: CorePlayQueue::getTrack 0 Apr 21 08:52:03 volumio volumio[578]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":43153,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Romantic Guitar Music ❤️ The Best Guitar Melodies For Your Most Romantic Moments ❤️","artist":"Romantic Guitar","album":"YouTube","uri":"https://rr1---sn-8pxuuxa-i5oel.googlevideo.com/videoplayback?expire=1713685915&ei=O3EkZorxL6ah7OsPwrGm8A4&ip=116.96.44.234&id=o-AAb9uUrfiAyUIahU0IAI_j0vefvGyqfoumuyLobRrz9g&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nD&mm=31%2C29&mn=sn-8pxuuxa-i5oel%2Csn-8pxuuxa-i5ozs&ms=au%2Crdu&mv=m&mvi=1&pcm2cms=yes&pl=23&initcwndbps=2191250&bui=AaUN6a0soEpZO8nzdisZIFSj2S9xFifPeCGjJ53R6gRSXzOaILT5qxi-5nb7hV5OI4HPgz2dZADtsiTC&spc=UWF9f0CUq1yclWCDGlW8noBDsJdmVWV5FhK4NcByn4b8kEN2Xg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fAyDfWAhyNAFReF_OkMWI2EQ&gir=yes&clen=865542155&dur=43152.901&lmt=1702862691176729&mt=1713663655&fvip=8&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=BzvStp8f0W8YWQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhANdJycfNlUA4Gc2Oq1Y-jwQXGdOgP0UP5hs2MKZafAQ6AiEAmlnTR4SgFsn8eIaFNk6zGdk6L6HDnyDWXCTlhqCJYko%3D&sig=AJfQdSswRQIhAJOdaA-9UjtM0cHIuFumE1t5xjeY67du9FAZy3LBntUsAiAK0o6aHPgwAUcDiXQ0uz-7jYw7qoCCmeW1KkqDHKsInA%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 08:52:03 volumio volumio[578]: verbose: CURRENT POSITION 0 Apr 21 08:52:03 volumio volumio[578]: info: CoreStateMachine::syncState stateService play Apr 21 08:52:03 volumio volumio[578]: info: CoreStateMachine::syncState currentStatus stop Apr 21 08:52:03 volumio volumio[578]: info: ------------------------------ 455ms Apr 21 08:52:03 volumio volumio[578]: info: sendMpdCommand playlistinfo took 139 milliseconds Apr 21 08:52:03 volumio volumio[578]: verbose: ControllerMpd::parseTrackInfo Apr 21 08:52:03 volumio volumio[578]: info: ControllerMpd::pushState Apr 21 08:52:03 volumio volumio[578]: info: CoreCommandRouter::servicePushState Apr 21 08:52:03 volumio volumio[578]: info: CorePlayQueue::getTrack 0 Apr 21 08:52:03 volumio volumio[578]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":43153,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Romantic Guitar Music ❤️ The Best Guitar Melodies For Your Most Romantic Moments ❤️","artist":"Romantic Guitar","album":"YouTube","uri":"https://rr1---sn-8pxuuxa-i5oel.googlevideo.com/videoplayback?expire=1713685915&ei=O3EkZorxL6ah7OsPwrGm8A4&ip=116.96.44.234&id=o-AAb9uUrfiAyUIahU0IAI_j0vefvGyqfoumuyLobRrz9g&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=nD&mm=31%2C29&mn=sn-8pxuuxa-i5oel%2Csn-8pxuuxa-i5ozs&ms=au%2Crdu&mv=m&mvi=1&pcm2cms=yes&pl=23&initcwndbps=2191250&bui=AaUN6a0soEpZO8nzdisZIFSj2S9xFifPeCGjJ53R6gRSXzOaILT5qxi-5nb7hV5OI4HPgz2dZADtsiTC&spc=UWF9f0CUq1yclWCDGlW8noBDsJdmVWV5FhK4NcByn4b8kEN2Xg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fAyDfWAhyNAFReF_OkMWI2EQ&gir=yes&clen=865542155&dur=43152.901&lmt=1702862691176729&mt=1713663655&fvip=8&keepalive=yes&c=WEB&sefc=1&txp=4532434&n=BzvStp8f0W8YWQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ALClDIEwRgIhANdJycfNlUA4Gc2Oq1Y-jwQXGdOgP0UP5hs2MKZafAQ6AiEAmlnTR4SgFsn8eIaFNk6zGdk6L6HDnyDWXCTlhqCJYko%3D&sig=AJfQdSswRQIhAJOdaA-9UjtM0cHIuFumE1t5xjeY67du9FAZy3LBntUsAiAK0o6aHPgwAUcDiXQ0uz-7jYw7qoCCmeW1KkqDHKsInA%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Apr 21 08:52:03 volumio volumio[578]: verbose: CURRENT POSITION 0 Apr 21 08:52:03 volumio volumio[578]: info: CoreStateMachine::syncState stateService play Apr 21 08:52:03 volumio volumio[578]: info: CoreStateMachine::syncState currentStatus play Apr 21 08:52:03 volumio volumio[578]: info: Received an update from plugin. extracting info from payload Apr 21 08:52:03 volumio volumio[578]: info: CoreStateMachine::pushState Apr 21 08:52:03 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 08:52:03 volumio volumio[578]: info: CoreCommandRouter::volumioPushState Apr 21 08:52:03 volumio volumio[578]: info: CoreStateMachine::pushState Apr 21 08:52:03 volumio volumio[578]: info: CoreCommandRouter::volumioPushState Apr 21 08:52:03 volumio volumio[578]: info: ------------------------------ 855ms Apr 21 08:52:04 volumio volumio[578]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 21 08:52:04 volumio volumio[578]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 21 08:52:05 volumio volumio[578]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 21 08:52:05 volumio volumio[578]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 21 08:52:28 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 08:52:28 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 21 08:52:29 volumio ntpd[414]: Soliciting pool server 2001:678:8::123 Apr 21 08:52:35 volumio volumio[578]: info: Enabling plugin fusiondsp Apr 21 08:52:35 volumio volumio[578]: info: Loading plugin "fusiondsp"... Apr 21 08:52:38 volumio volumio[578]: info: Preparing to generate the ALSA configuration file Apr 21 08:52:38 volumio volumio[578]: Upnp client error: Error: This socket has been ended by the other party Apr 21 08:52:38 volumio volumio[578]: info: Asound.conf file unchanged, so no further update is needed Apr 21 08:52:38 volumio volumio[578]: info: Output device has changed, restarting MPD Apr 21 08:52:39 volumio volumio[578]: info: Output device has changed, restarting Shairport Sync Apr 21 08:52:39 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 08:52:39 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:39 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 08:52:39 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 08:52:39 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Apr 21 08:52:39 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 08:52:39 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 08:52:39 volumio systemd[1]: Stopping Music Player Daemon... Apr 21 08:52:39 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048: -121 Apr 21 08:52:40 volumio systemd[1]: mpd.service: Succeeded. Apr 21 08:52:40 volumio systemd[1]: Stopped Music Player Daemon. Apr 21 08:52:40 volumio systemd[1]: Starting Music Player Daemon... Apr 21 08:52:40 volumio volumio[578]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 08:52:40 volumio volumio[578]: info: PLUGIN START: fusiondsp Apr 21 08:52:40 volumio volumio[578]: info: Loading i18n strings for locale vi Apr 21 08:52:40 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 21 08:52:40 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:40 volumio volumio[578]: info: FusionDsp - mixtype--------------------- Hardware Apr 21 08:52:40 volumio volumio[578]: info: Preparing to generate the ALSA configuration file Apr 21 08:52:41 volumio volumio[578]: info: Done. Apr 21 08:52:41 volumio volumio[578]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 21 08:52:41 volumio volumio[578]: info: Reading ALSA contributions from plugins. Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 08:52:41 volumio volumio[578]: info: MPD Permissions set Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:41 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:42 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 08:52:42 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:46 volumio volumio[578]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 21 08:52:46 volumio volumio[578]: info: camilladsp service started and running in background, instance 1 Apr 21 08:52:46 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 08:52:46 volumio volumio[578]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 21 08:52:46 volumio volumio[578]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 21 08:52:46 volumio volumio[578]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 21 08:52:46 volumio volumio[578]: info: FusionDsp loaded Apr 21 08:52:46 volumio volumio[578]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 08:52:47 volumio sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 21 08:52:47 volumio sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 08:52:47 volumio sudo[1332]: pam_unix(sudo:session): session closed for user root Apr 21 08:52:47 volumio volumio[578]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 21 08:52:47 volumio volumio[578]: info: Adding Signal Path Element [object Object] Apr 21 08:52:47 volumio volumio[578]: info: Adding fusiondspeq DSP Signal Path Element Apr 21 08:52:47 volumio volumio[578]: info: FusionDsp - ---- installed callbackRead Apr 21 08:52:47 volumio volumio[578]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 08:52:47 volumio volumio[578]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 21 08:52:47 volumio volumio[578]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 21 08:52:47 volumio volumio[578]: at onErrorNT (internal/child_process.js:465:16) Apr 21 08:52:47 volumio volumio[578]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 21 08:52:47 volumio volumio[578]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 21 08:52:47 volumio volumio[578]: at listOnTimeout (internal/timers.js:523:9) Apr 21 08:52:47 volumio volumio[578]: at processTimers (internal/timers.js:497:7) { Apr 21 08:52:47 volumio volumio[578]: errno: -2, Apr 21 08:52:47 volumio volumio[578]: code: 'ENOENT', Apr 21 08:52:47 volumio volumio[578]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 21 08:52:47 volumio volumio[578]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 21 08:52:47 volumio volumio[578]: spawnargs: [ Apr 21 08:52:47 volumio volumio[578]: '-p', Apr 21 08:52:47 volumio volumio[578]: 9876, Apr 21 08:52:47 volumio volumio[578]: '-o', Apr 21 08:52:47 volumio volumio[578]: '/tmp/camilladsp.log', Apr 21 08:52:47 volumio volumio[578]: '-l', Apr 21 08:52:47 volumio volumio[578]: 'warn', Apr 21 08:52:47 volumio volumio[578]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 21 08:52:47 volumio volumio[578]: ] Apr 21 08:52:47 volumio volumio[578]: } Apr 21 08:52:47 volumio volumio[578]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 08:52:55 volumio sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 08:51 Apr 21 08:52:55 volumio sudo[1343]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"