-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Thu 2024-08-22 21:25:47 +07. -- Aug 22 21:24:06 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Aug 22 21:24:06 volumio volumio[1159]: info: [youtube2-browse] browseUri: youtube2 Aug 22 21:24:06 volumio volumio[1159]: info: Preload queue cleared Aug 22 21:24:12 volumio volumio[1159]: Searching plugin music_service/youtube2 Aug 22 21:24:12 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Aug 22 21:24:13 volumio volumio[1159]: SearchHeader not found! Aug 22 21:24:13 volumio volumio[1159]: 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! Aug 22 21:24:13 volumio volumio[1159]: Introspected and JIT generated this class in the meantime: Aug 22 21:24:13 volumio volumio[1159]: class SearchHeader extends YTNode { Aug 22 21:24:13 volumio volumio[1159]: static type = 'SearchHeader'; Aug 22 21:24:13 volumio volumio[1159]: search_filter_button: YTNodes.Button | null; Aug 22 21:24:13 volumio volumio[1159]: constructor(data: RawNode) { Aug 22 21:24:13 volumio volumio[1159]: super(); Aug 22 21:24:13 volumio volumio[1159]: this.search_filter_button = Parser.parseItem(data.searchFilterButton, [ YTNodes.Button ]); Aug 22 21:24:13 volumio volumio[1159]: } Aug 22 21:24:13 volumio volumio[1159]: } Aug 22 21:24:13 volumio volumio[1159]: info: All search sources collected, pushing search results Aug 22 21:24:14 volumio volumio[1159]: Searching plugin music_service/youtube2 Aug 22 21:24:14 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Aug 22 21:24:15 volumio volumio[1159]: SearchHeader changed! Aug 22 21:24:15 volumio volumio[1159]: The following keys where altered: chip_bar Aug 22 21:24:15 volumio volumio[1159]: The class has changed to: Aug 22 21:24:15 volumio volumio[1159]: class SearchHeader extends YTNode { Aug 22 21:24:15 volumio volumio[1159]: static type = 'SearchHeader'; Aug 22 21:24:15 volumio volumio[1159]: search_filter_button: YTNodes.Button | null; Aug 22 21:24:15 volumio volumio[1159]: chip_bar?: YTNodes.ChipCloud | null; Aug 22 21:24:15 volumio volumio[1159]: constructor(data: RawNode) { Aug 22 21:24:15 volumio volumio[1159]: super(); Aug 22 21:24:15 volumio volumio[1159]: this.search_filter_button = Parser.parseItem(data.searchFilterButton, [ YTNodes.Button ]); Aug 22 21:24:15 volumio volumio[1159]: this.chip_bar = Reflect.has(data, 'chipBar') ? Parser.parseItem(data.chipBar, [ YTNodes.ChipCloud ]) : undefined; Aug 22 21:24:15 volumio volumio[1159]: } Aug 22 21:24:15 volumio volumio[1159]: } Aug 22 21:24:15 volumio volumio[1159]: info: All search sources collected, pushing search results Aug 22 21:24:31 volumio volumio[1159]: info: Preload queue cleared Aug 22 21:24:31 volumio volumio[1159]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::ClearQueue Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::stop Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::clearPlayQueue Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::saveQueue Aug 22 21:24:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushQueue Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::addQueueItems Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::addQueueItems Aug 22 21:24:31 volumio volumio[1159]: info: Preload queue cleared Aug 22 21:24:31 volumio volumio[1159]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Quang%20D%C5%A9ng%20-%20V%C3%AC%20%C4%90%C3%B3%20L%C3%A0%20Em%20-%2035%20Ca%20Kh%C3%BAc%20Nh%E1%BA%A1c%20Tr%E1%BB%AF%20T%C3%ACnh%20Hay%20%C4%90%E1%BB%83%20%C4%90%E1%BB%9Di%20C%E1%BB%A7a%20Quang%20D%C5%A9ng%2C%20Nghe%20L%C3%A0%20Nghi%E1%BB%87n%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20Hay%20Vi%E1%BB%87t%20Nam%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FkNeRlVmiLQQ%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAO5TUX83AOKxZknSiTThK0jHoKYA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kNeRlVmiLQQ%22%2C%22params%22%3A%22qgMFcXVhbme6AxESD1JEUU1sYUJPRndzYXAyWboDCgjW65v13N-Qoi26AwsIguLokKDclqfOAboDCgj3s766n7eZ3H26AwoI76jx5_eWlaExugMKCJSE04num5CeGroDCgjUkuOzzofBt2a6AwsIkP-e74S7tf6NAboDCgjyzM_xmcbSjg26AwoI8YWes735lN9OugMLCO2Yx4ah6tquyAG6AwsInrvf8sHAsczNAboDCgj2zs3Fx7T53UC6AwsI3Jujx9fSjZTuAboDCgjt66fHneKtuTa6AwoIje-D76rGz8l5ugMJCMaL1vfhg_UNugMKCIvboLvw4YvUELoDCgjNv-LRk9r79zryAwUNf_P3Pg%253D%253D%22%7D%7D%7D Aug 22 21:24:31 volumio volumio[1159]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Quang%20D%C5%A9ng%20-%20V%C3%AC%20%C4%90%C3%B3%20L%C3%A0%20Em%20-%2035%20Ca%20Kh%C3%BAc%20Nh%E1%BA%A1c%20Tr%E1%BB%AF%20T%C3%ACnh%20Hay%20%C4%90%E1%BB%83%20%C4%90%E1%BB%9Di%20C%E1%BB%A7a%20Quang%20D%C5%A9ng%2C%20Nghe%20L%C3%A0%20Nghi%E1%BB%87n%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20Hay%20Vi%E1%BB%87t%20Nam%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FkNeRlVmiLQQ%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAO5TUX83AOKxZknSiTThK0jHoKYA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kNeRlVmiLQQ%22%2C%22params%22%3A%22qgMFcXVhbme6AxESD1JEUU1sYUJPRndzYXAyWboDCgjW65v13N-Qoi26AwsIguLokKDclqfOAboDCgj3s766n7eZ3H26AwoI76jx5_eWlaExugMKCJSE04num5CeGroDCgjUkuOzzofBt2a6AwsIkP-e74S7tf6NAboDCgjyzM_xmcbSjg26AwoI8YWes735lN9OugMLCO2Yx4ah6tquyAG6AwsInrvf8sHAsczNAboDCgj2zs3Fx7T53UC6AwsI3Jujx9fSjZTuAboDCgjt66fHneKtuTa6AwoIje-D76rGz8l5ugMJCMaL1vfhg_UNugMKCIvboLvw4YvUELoDCgjNv-LRk9r79zryAwUNf_P3Pg%253D%253D%22%7D%7D%7D in service youtube2 Aug 22 21:24:31 volumio volumio[1159]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Quang%20D%C5%A9ng%20-%20V%C3%AC%20%C4%90%C3%B3%20L%C3%A0%20Em%20-%2035%20Ca%20Kh%C3%BAc%20Nh%E1%BA%A1c%20Tr%E1%BB%AF%20T%C3%ACnh%20Hay%20%C4%90%E1%BB%83%20%C4%90%E1%BB%9Di%20C%E1%BB%A7a%20Quang%20D%C5%A9ng%2C%20Nghe%20L%C3%A0%20Nghi%E1%BB%87n%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20Hay%20Vi%E1%BB%87t%20Nam%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FkNeRlVmiLQQ%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAO5TUX83AOKxZknSiTThK0jHoKYA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kNeRlVmiLQQ%22%2C%22params%22%3A%22qgMFcXVhbme6AxESD1JEUU1sYUJPRndzYXAyWboDCgjW65v13N-Qoi26AwsIguLokKDclqfOAboDCgj3s766n7eZ3H26AwoI76jx5_eWlaExugMKCJSE04num5CeGroDCgjUkuOzzofBt2a6AwsIkP-e74S7tf6NAboDCgjyzM_xmcbSjg26AwoI8YWes735lN9OugMLCO2Yx4ah6tquyAG6AwsInrvf8sHAsczNAboDCgj2zs3Fx7T53UC6AwsI3Jujx9fSjZTuAboDCgjt66fHneKtuTa6AwoIje-D76rGz8l5ugMJCMaL1vfhg_UNugMKCIvboLvw4YvUELoDCgjNv-LRk9r79zryAwUNf_P3Pg%253D%253D%22%7D%7D%7D Aug 22 21:24:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushQueue Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::saveQueue Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::updateTrackBlock Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::getTrackBlock Aug 22 21:24:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPlay Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::play index 0 Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::stop Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::play index undefined Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::startPlaybackTimer Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:24:31 volumio volumio[1159]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kNeRlVmiLQQ%22%2C%22params%22%3A%22qgMFcXVhbme6AxESD1JEUU1sYUJPRndzYXAyWboDCgjW65v13N-Qoi26AwsIguLokKDclqfOAboDCgj3s766n7eZ3H26AwoI76jx5_eWlaExugMKCJSE04num5CeGroDCgjUkuOzzofBt2a6AwsIkP-e74S7tf6NAboDCgjyzM_xmcbSjg26AwoI8YWes735lN9OugMLCO2Yx4ah6tquyAG6AwsInrvf8sHAsczNAboDCgj2zs3Fx7T53UC6AwsI3Jujx9fSjZTuAboDCgjt66fHneKtuTa6AwoIje-D76rGz8l5ugMJCMaL1vfhg_UNugMKCIvboLvw4YvUELoDCgjNv-LRk9r79zryAwUNf_P3Pg%253D%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Quang%20D%C5%A9ng%20-%20V%C3%AC%20%C4%90%C3%B3%20L%C3%A0%20Em%20-%2035%20Ca%20Kh%C3%BAc%20Nh%E1%BA%A1c%20Tr%E1%BB%AF%20T%C3%ACnh%20Hay%20%C4%90%E1%BB%83%20%C4%90%E1%BB%9Di%20C%E1%BB%A7a%20Quang%20D%C5%A9ng%2C%20Nghe%20L%C3%A0%20Nghi%E1%BB%87n%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20Hay%20Vi%E1%BB%87t%20Nam%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FkNeRlVmiLQQ%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAO5TUX83AOKxZknSiTThK0jHoKYA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kNeRlVmiLQQ%22%2C%22params%22%3A%22qgMFcXVhbme6AxESD1JEUU1sYUJPRndzYXAyWboDCgjW65v13N-Qoi26AwsIguLokKDclqfOAboDCgj3s766n7eZ3H26AwoI76jx5_eWlaExugMKCJSE04num5CeGroDCgjUkuOzzofBt2a6AwsIkP-e74S7tf6NAboDCgjyzM_xmcbSjg26AwoI8YWes735lN9OugMLCO2Yx4ah6tquyAG6AwsInrvf8sHAsczNAboDCgj2zs3Fx7T53UC6AwsI3Jujx9fSjZTuAboDCgjt66fHneKtuTa6AwoIje-D76rGz8l5ugMJCMaL1vfhg_UNugMKCIvboLvw4YvUELoDCgjNv-LRk9r79zryAwUNf_P3Pg%253D%253D%22%7D%7D%7D Aug 22 21:24:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:24:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 21:24:31 volumio volumio[1159]: info: sendMpdCommand stop took 1 milliseconds Aug 22 21:24:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 21:24:31 volumio volumio[1159]: info: Aug 22 21:24:31 volumio volumio[1159]: ---------------------------- MPD announces system playlist update Aug 22 21:24:31 volumio volumio[1159]: info: Ignoring MPD Status Update Aug 22 21:24:31 volumio volumio[1159]: info: sendMpdCommand clear took 2 milliseconds Aug 22 21:24:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube" Aug 22 21:24:31 volumio volumio[1159]: info: Aug 22 21:24:31 volumio volumio[1159]: ---------------------------- MPD announces system playlist update Aug 22 21:24:31 volumio volumio[1159]: info: Ignoring MPD Status Update Aug 22 21:24:31 volumio volumio[1159]: error: updateQueue error: null Aug 22 21:24:31 volumio volumio[1159]: info: Aug 22 21:24:31 volumio volumio[1159]: ---------------------------- MPD announces system playlist update Aug 22 21:24:31 volumio volumio[1159]: info: Ignoring MPD Status Update Aug 22 21:24:31 volumio volumio[1159]: info: ------------------------------ 4ms Aug 22 21:24:31 volumio volumio[1159]: info: sendMpdCommand addid "https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube" took 4 milliseconds Aug 22 21:24:31 volumio volumio[1159]: info: ------------------------------ 3ms Aug 22 21:24:31 volumio volumio[1159]: verbose: MPD COMMAND [object Object] Aug 22 21:24:31 volumio volumio[1159]: verbose: MPD COMMAND [object Object] Aug 22 21:24:31 volumio volumio[1159]: verbose: MPD COMMAND [object Object] Aug 22 21:24:31 volumio volumio[1159]: info: Aug 22 21:24:31 volumio volumio[1159]: ---------------------------- MPD announces system playlist update Aug 22 21:24:31 volumio volumio[1159]: info: Ignoring MPD Status Update Aug 22 21:24:31 volumio volumio[1159]: info: Aug 22 21:24:31 volumio volumio[1159]: ---------------------------- MPD announces system playlist update Aug 22 21:24:31 volumio volumio[1159]: info: Ignoring MPD Status Update Aug 22 21:24:31 volumio volumio[1159]: info: ------------------------------ 5ms Aug 22 21:24:31 volumio volumio[1159]: info: ------------------------------ 2ms Aug 22 21:24:31 volumio volumio[1159]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 22 21:24:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand play Aug 22 21:24:31 volumio volumio[1159]: info: Aug 22 21:24:31 volumio volumio[1159]: ---------------------------- MPD announces system playlist update Aug 22 21:24:31 volumio volumio[1159]: info: Ignoring MPD Status Update Aug 22 21:24:31 volumio volumio[1159]: info: ------------------------------ 4ms Aug 22 21:24:31 volumio volumio[1159]: info: sendMpdCommand play took 3 milliseconds Aug 22 21:24:31 volumio volumio[1159]: info: ------------------------------ 2ms Aug 22 21:24:33 volumio volumio[1159]: info: Aug 22 21:24:33 volumio volumio[1159]: ---------------------------- MPD announces state update: player Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::getState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:24:33 volumio volumio[1159]: info: Aug 22 21:24:33 volumio volumio[1159]: ---------------------------- MPD announces state update: player Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::getState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:24:33 volumio volumio[1159]: info: Aug 22 21:24:33 volumio volumio[1159]: ---------------------------- MPD announces state update: player Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::getState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand status took 4 milliseconds Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:24:33 volumio volumio[1159]: info: Aug 22 21:24:33 volumio volumio[1159]: ---------------------------- MPD announces state update: player Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::getState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand status took 6 milliseconds Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand status took 6 milliseconds Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand status took 3 milliseconds Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Aug 22 21:24:33 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:24:33 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10058,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Quang Dũng - Vì Đó Là Em - 35 Ca Khúc Nhạc Trữ Tình Hay Để Đời Của Quang Dũng, Nghe Là Nghiện","artist":"Nhạc Hay Việt Nam","album":"YouTube","uri":"https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Aug 22 21:24:33 volumio volumio[1159]: verbose: CURRENT POSITION 0 Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Aug 22 21:24:33 volumio volumio[1159]: info: ------------------------------ 16ms Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 21:24:33 volumio volumio[1159]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:24:33 volumio volumio[1159]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Aug 22 21:24:33 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:24:33 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10058,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Quang Dũng - Vì Đó Là Em - 35 Ca Khúc Nhạc Trữ Tình Hay Để Đời Của Quang Dũng, Nghe Là Nghiện","artist":"Nhạc Hay Việt Nam","album":"YouTube","uri":"https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Aug 22 21:24:33 volumio volumio[1159]: verbose: CURRENT POSITION 0 Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:24:33 volumio volumio[1159]: info: Received an update from plugin. extracting info from payload Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Aug 22 21:24:33 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:24:33 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":889,"duration":10058,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Quang Dũng - Vì Đó Là Em - 35 Ca Khúc Nhạc Trữ Tình Hay Để Đời Của Quang Dũng, Nghe Là Nghiện","artist":"Nhạc Hay Việt Nam","album":"YouTube","uri":"https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Aug 22 21:24:33 volumio volumio[1159]: verbose: CURRENT POSITION 0 Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:24:33 volumio volumio[1159]: info: Received an update from plugin. extracting info from payload Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:24:33 volumio volumio[1159]: info: ControllerMpd::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Aug 22 21:24:33 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:24:33 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":10058,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Quang Dũng - Vì Đó Là Em - 35 Ca Khúc Nhạc Trữ Tình Hay Để Đời Của Quang Dũng, Nghe Là Nghiện","artist":"Nhạc Hay Việt Nam","album":"YouTube","uri":"https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Aug 22 21:24:33 volumio volumio[1159]: verbose: CURRENT POSITION 0 Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:24:33 volumio volumio[1159]: info: Received an update from plugin. extracting info from payload Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:24:33 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:24:33 volumio volumio[1159]: info: ------------------------------ 52ms Aug 22 21:24:33 volumio volumio[1159]: info: ------------------------------ 52ms Aug 22 21:24:33 volumio volumio[1159]: info: ------------------------------ 49ms Aug 22 21:24:33 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:24:33 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:24:33 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:24:33 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:24:33 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:24:33 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:24:35 volumio ntpd[1462]: Soliciting pool server 203.113.174.44 Aug 22 21:24:37 volumio ntpd[1462]: Soliciting pool server 203.113.174.44 Aug 22 21:24:39 volumio ntpd[1462]: Soliciting pool server 2a0c:b847:ffff:79::e53c Aug 22 21:24:40 volumio ntpd[1462]: Soliciting pool server 203.113.174.44 Aug 22 21:24:44 volumio volumio[1159]: info: CoreCommandRouter::volumioGetState Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 22 21:24:45 volumio volumio[1159]: info: Retrieving Cloud Streaming UI Aug 22 21:24:45 volumio volumio[1159]: info: Getting Tidal Cloud Configuration Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 22 21:24:45 volumio volumio[1159]: info: Getting Qobuz Cloud Configuration Aug 22 21:24:45 volumio volumio[1159]: info: Asking plugin for UI Config Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 22 21:24:45 volumio volumio[1159]: info: Getting Spotify Cloud Configuration Aug 22 21:24:45 volumio volumio[1159]: info: Asking plugin for UI Config Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 22 21:24:45 volumio volumio[1159]: info: Saving Spotify Acccount Aug 22 21:24:45 volumio volumio[1159]: info: Got Tidal Cloud Configuration Aug 22 21:24:45 volumio volumio[1159]: info: Got it Aug 22 21:24:45 volumio volumio[1159]: info: Got it Aug 22 21:24:45 volumio volumio[1159]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 22 21:24:45 volumio volumio[1159]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::volumioGetBrowseSources Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::volumioGetBrowseSources Aug 22 21:24:45 volumio volumio[1159]: info: CoreCommandRouter::volumioGetBrowseSources Aug 22 21:24:46 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:24:46 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 22 21:24:50 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:24:54 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:24:58 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:02 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand update Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:25:03 volumio volumio[1159]: info: Aug 22 21:25:03 volumio volumio[1159]: ---------------------------- MPD announces state update: update Aug 22 21:25:03 volumio volumio[1159]: info: sendMpdCommand update took 2 milliseconds Aug 22 21:25:03 volumio volumio[1159]: info: ControllerMpd::getState Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:25:03 volumio volumio[1159]: info: Aug 22 21:25:03 volumio volumio[1159]: ---------------------------- MPD announces state update: update Aug 22 21:25:03 volumio volumio[1159]: info: ControllerMpd::getState Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Aug 22 21:25:03 volumio volumio[1159]: info: sendMpdCommand status took 3 milliseconds Aug 22 21:25:03 volumio volumio[1159]: info: sendMpdCommand status took 3 milliseconds Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:25:03 volumio volumio[1159]: info: Command Router : Notfying DB Updatetrue Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:25:03 volumio volumio[1159]: info: sendMpdCommand status took 116 milliseconds Aug 22 21:25:03 volumio volumio[1159]: info: sendMpdCommand status took 116 milliseconds Aug 22 21:25:03 volumio volumio[1159]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:25:03 volumio volumio[1159]: info: Command Router : Notfying DB Updatetrue Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::parseState Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:25:03 volumio volumio[1159]: info: ControllerMpd::pushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Aug 22 21:25:03 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:25:03 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30737,"duration":10058,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Quang Dũng - Vì Đó Là Em - 35 Ca Khúc Nhạc Trữ Tình Hay Để Đời Của Quang Dũng, Nghe Là Nghiện","artist":"Nhạc Hay Việt Nam","album":"YouTube","uri":"https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Aug 22 21:25:03 volumio volumio[1159]: verbose: CURRENT POSITION 0 Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:25:03 volumio volumio[1159]: info: Received an update from plugin. extracting info from payload Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:25:03 volumio volumio[1159]: info: ------------------------------ 151ms Aug 22 21:25:03 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:25:03 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:25:03 volumio volumio[1159]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 22 21:25:03 volumio volumio[1159]: verbose: ControllerMpd::parseTrackInfo Aug 22 21:25:03 volumio volumio[1159]: info: ControllerMpd::pushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Aug 22 21:25:03 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Aug 22 21:25:03 volumio volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30737,"duration":10058,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Quang Dũng - Vì Đó Là Em - 35 Ca Khúc Nhạc Trữ Tình Hay Để Đời Của Quang Dũng, Nghe Là Nghiện","artist":"Nhạc Hay Việt Nam","album":"YouTube","uri":"https://rr2---sn-8qj-2iil.googlevideo.com/videoplayback?expire=1724333523&ei=c-nGZpWlMaHS1d8PwP618QE&ip=14.183.85.180&id=o-AKfQjAPGJjRh4FSeprkYQJLiMJMbbcYrJCcUfJkDeaEx&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Oy&mm=31%2C29&mn=sn-8qj-2iil%2Csn-8qj-nbo66&ms=au%2Crdu&mv=m&mvi=2&pl=22&initcwndbps=1590000&bui=AQmm2eyEDoMT1u3ZgRL90-FBZDaxnzwpMZ0_OVtjWy1br5Ujqn9a3nqjhlnSaoty3vr6Eki-j3m0_ZDZ&spc=Mv1m9m_V191AuAOC3WZnhUKAfpx7IKu9zzqqCnipYc9-Syvxnw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=12p7pqB2Dl_M-nbFR8oW84sQ&rqh=1&gir=yes&clen=157898678&dur=10058.021&lmt=1711620090033536&mt=1724311545&fvip=3&keepalive=yes&c=WEB&sefc=1&txp=4432434&n=2atWAJS9OSoOtw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AGtxev0wRAIgD53ogmL20ZlNJUK-8yLTBTbMil1l-4ixjRFC6FqBZCQCIDB7KepfzEvokWbQd5zsz6ABcbQTu_F90nLBj22fXoa5&sig=AJfQdSswRQIhAMTzQpkXhVFRLoI3fK7JMSxxewECJgjAm-q-vnP_IY3qAiBRXxo9WA7c05ZOwDPwQ462LhVPB4evDgOGJ-eC9Wac3Q%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Aug 22 21:25:03 volumio volumio[1159]: verbose: CURRENT POSITION 0 Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::syncState stateService play Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus play Aug 22 21:25:03 volumio volumio[1159]: info: Received an update from plugin. extracting info from payload Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreStateMachine::pushState Aug 22 21:25:03 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Aug 22 21:25:03 volumio volumio[1159]: info: ------------------------------ 171ms Aug 22 21:25:03 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:25:03 volumio volumio[1159]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 22 21:25:06 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:10 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:14 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:18 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:22 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:23 volumio volumio[1159]: info: Enabling MyMusic plugin bluetooth Aug 22 21:25:23 volumio volumio[1159]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Aug 22 21:25:23 volumio volumio[1159]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Aug 22 21:25:23 volumio volumio[1159]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Aug 22 21:25:23 volumio volumio[1159]: error: Could not Enable MyMusic Plugin: Error Aug 22 21:25:26 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:30 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:34 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:38 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:38 volumio volumio[1159]: info: Enabling MyMusic plugin multiroom Aug 22 21:25:38 volumio volumio[1159]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Aug 22 21:25:38 volumio volumio[1159]: info: [MyVolumio PluginManager] Plugin audio_interface multiroom not in user plan, not enabling Aug 22 21:25:38 volumio volumio[1159]: info: Error: Plugin audio_interface multiroom not in user plan, not enabling Aug 22 21:25:38 volumio volumio[1159]: error: Could not Enable MyMusic Plugin: Error Aug 22 21:25:41 volumio ntpd[1462]: Soliciting pool server 103.130.217.41 Aug 22 21:25:42 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:42 volumio ntpd[1462]: Soliciting pool server 103.130.217.41 Aug 22 21:25:45 volumio ntpd[1462]: Soliciting pool server 203.113.174.44 Aug 22 21:25:45 volumio volumio[1159]: info: Disabling MyMusic plugin upnp Aug 22 21:25:45 volumio sudo[2331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 22 21:25:45 volumio sudo[2331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 21:25:45 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 22 21:25:45 volumio volumio[1159]: error: Upnp client error: Error: This socket has been ended by the other party Aug 22 21:25:46 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 21:25:46 volumio ntpd[1462]: Soliciting pool server 103.130.217.41 Aug 22 21:25:47 volumio volumio[1159]: info: Enabling MyMusic plugin upnp Aug 22 21:25:47 volumio volumio[1159]: info: Enabling plugin upnp Aug 22 21:25:47 volumio volumio[1159]: info: Loading plugin "upnp"... Aug 22 21:25:47 volumio volumio[1159]: info: [1724336747013] Starting Upmpd Daemon Aug 22 21:25:47 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 22 21:25:47 volumio volumio[1159]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 21:25:47 volumio volumio[1159]: Error: listen EADDRINUSE: address already in use :::6599 Aug 22 21:25:47 volumio volumio[1159]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Aug 22 21:25:47 volumio volumio[1159]: at listenInCluster (net.js:1379:12) Aug 22 21:25:47 volumio volumio[1159]: at Server.listen (net.js:1465:7) Aug 22 21:25:47 volumio volumio[1159]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 22 21:25:47 volumio volumio[1159]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 22 21:25:47 volumio volumio[1159]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 22 21:25:47 volumio volumio[1159]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 22 21:25:47 volumio volumio[1159]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Aug 22 21:25:47 volumio volumio[1159]: code: 'EADDRINUSE', Aug 22 21:25:47 volumio volumio[1159]: errno: -98, Aug 22 21:25:47 volumio volumio[1159]: syscall: 'listen', Aug 22 21:25:47 volumio volumio[1159]: address: '::', Aug 22 21:25:47 volumio volumio[1159]: port: 6599 Aug 22 21:25:47 volumio volumio[1159]: } Aug 22 21:25:47 volumio volumio[1159]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 21:25:47 volumio sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-22 21:24 Aug 22 21:25:47 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"