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