Oct 27 17:15:00 volumio volumio[1148]: warn: [youtube2] VideoModel.getInfo(nfhtK-vvids): stream validation failed (403 - Forbidden); retrying after 2s... Oct 27 17:15:02 volumio volumio[1148]: warn: [youtube2] VideoModel.getInfo(nfhtK-vvids): stream validation failed (403 - Forbidden); retrying after 2s... Oct 27 17:15:04 volumio volumio[1148]: warn: [youtube2] VideoModel.getInfo(nfhtK-vvids): failed to validate stream URL "https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00" (retried 3 times in 6.152s). Oct 27 17:15:04 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand stop took 1 milliseconds Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand clear took 0 milliseconds Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube" Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 3ms Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand addid "https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube" took 2 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 1ms Oct 27 17:15:04 volumio volumio[1148]: verbose: MPD COMMAND [object Object] Oct 27 17:15:04 volumio volumio[1148]: verbose: MPD COMMAND [object Object] Oct 27 17:15:04 volumio volumio[1148]: verbose: MPD COMMAND [object Object] Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:04 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand play took 3 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand status took 5 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand status took 5 milliseconds Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:04 volumio volumio[1148]: info: Oct 27 17:15:04 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand status took 12 milliseconds Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand status took 12 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand clearerror took 10 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand status took 9 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand clearerror took 9 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand status took 4 milliseconds Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:04 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"【公式】中森明菜「I hope so -JAZZ-」","artist":"AKINA NAKAMORI OFFICIAL","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:04 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: No code Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:04 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"【公式】中森明菜「I hope so -JAZZ-」","artist":"AKINA NAKAMORI OFFICIAL","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:04 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: No code Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 47ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 46ms Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand clearerror took 44 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand clearerror took 43 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 41 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 27 17:15:04 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:04 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:04 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"【公式】中森明菜「I hope so -JAZZ-」","artist":"AKINA NAKAMORI OFFICIAL","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:04 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: No code Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:04 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"【公式】中森明菜「I hope so -JAZZ-」","artist":"AKINA NAKAMORI OFFICIAL","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:04 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: No code Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:04 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"【公式】中森明菜「I hope so -JAZZ-」","artist":"AKINA NAKAMORI OFFICIAL","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:04 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: No code Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:04 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"【公式】中森明菜「I hope so -JAZZ-」","artist":"AKINA NAKAMORI OFFICIAL","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574498&ei=Air_aKjLL4Kb1d8PoOb7uAQ&ip=117.109.223.15&id=o-AJE0Jn_Z8S12UfVq5Yd-WhfRXCT1m5SfHe5nVyfkcibL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552898%2C&mh=rW&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm7dn7y&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&initcwndbps=3945000&siu=1&bui=AdEuB5R8bYm_IjClYzzMnUIhLh7nj9IrUHXaKGwQLFt4ZQvnVaOy3ORP4VLoldSNlnn1gSa_LA&spc=6b0G_PFfBomq83nScs9GeldpAub15kRm11g6W7VBIWa1DTE3EvIqRbhmizXiXdfgYgI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=I6HqGMsmauBCOFS_5DxhvIEQ&rqh=1&gir=yes&clen=4807721&dur=286.421&lmt=1761077160256846&mt=1761552535&fvip=1&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=7lKIDZVcZjogtcR&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALccJHAweVbgvmL4jJqw6N7dnk6mkyPLb5qhSeyJNQ9lAiEA_vBMtJk1-4FjZVre-oWqkk6u2E4BETuvFqL2ldkjq4Q%3D&sig=JfAdSswRQIhAOS84tYOzpQhDvA8UkohExl0ippu9G2n1lN4R_T9SIuYAiB0MdVwic3s1RVjLyjDGcU3qS1GEvlK12n7a78kHyOz&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:04 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: No code Oct 27 17:15:04 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:04 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 105ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 104ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 105ms Oct 27 17:15:04 volumio volumio[1148]: info: ------------------------------ 97ms Oct 27 17:15:06 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:15:06 volumio volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::ClearQueue Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:15:06 volumio volumio[1148]: info: CorePlayQueue::clearPlayQueue Oct 27 17:15:06 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:15:06 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::addQueueItems Oct 27 17:15:06 volumio volumio[1148]: info: CorePlayQueue::addQueueItems Oct 27 17:15:06 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:15:06 volumio volumio[1148]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%E2%91%A7%E3%80%90%E5%85%AC%E5%BC%8F%E3%80%91%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%EF%BC%8F%E3%82%B5%E3%82%B6%E3%83%B3%E3%83%BB%E3%82%A6%E3%82%A4%E3%83%B3%E3%83%89%20(%E3%82%A4%E3%83%BC%E3%82%B9%E3%83%88%E3%83%BB%E3%83%A9%E3%82%A4%E3%83%B4%20%E3%82%A4%E3%83%B3%E3%83%87%E3%83%83%E3%82%AF%E3%82%B923%20Live%20at%E3%82%88%E3%81%BF%E3%81%86%E3%82%8A%E3%83%A9%E3%83%B3%E3%83%89EAST%2C%201989.4.29%20%2630)AKINA%20NAKAMORI%2FSOUTHERN%20WIND%22%2C%22artist%22%3A%22Warner%20Music%20Japan%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiM7BvlxBpYc%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLC0lXRFOVWhll-QANClJesmc5c4XQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iM7BvlxBpYc%22%7D%7D%7D Oct 27 17:15:06 volumio volumio[1148]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22%E2%91%A7%E3%80%90%E5%85%AC%E5%BC%8F%E3%80%91%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%EF%BC%8F%E3%82%B5%E3%82%B6%E3%83%B3%E3%83%BB%E3%82%A6%E3%82%A4%E3%83%B3%E3%83%89%20(%E3%82%A4%E3%83%BC%E3%82%B9%E3%83%88%E3%83%BB%E3%83%A9%E3%82%A4%E3%83%B4%20%E3%82%A4%E3%83%B3%E3%83%87%E3%83%83%E3%82%AF%E3%82%B923%20Live%20at%E3%82%88%E3%81%BF%E3%81%86%E3%82%8A%E3%83%A9%E3%83%B3%E3%83%89EAST%2C%201989.4.29%20%2630)AKINA%20NAKAMORI%2FSOUTHERN%20WIND%22%2C%22artist%22%3A%22Warner%20Music%20Japan%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiM7BvlxBpYc%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLC0lXRFOVWhll-QANClJesmc5c4XQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iM7BvlxBpYc%22%7D%7D%7D Oct 27 17:15:06 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:15:06 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Oct 27 17:15:06 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Oct 27 17:15:06 volumio volumio[1148]: info: CoreCommandRouter::volumioPlay Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::play index 0 Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::play index undefined Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:15:06 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:06 volumio volumio[1148]: info: CoreStateMachine::startPlaybackTimer Oct 27 17:15:06 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:06 volumio volumio[1148]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iM7BvlxBpYc%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22%E2%91%A7%E3%80%90%E5%85%AC%E5%BC%8F%E3%80%91%E4%B8%AD%E6%A3%AE%E6%98%8E%E8%8F%9C%EF%BC%8F%E3%82%B5%E3%82%B6%E3%83%B3%E3%83%BB%E3%82%A6%E3%82%A4%E3%83%B3%E3%83%89%20(%E3%82%A4%E3%83%BC%E3%82%B9%E3%83%88%E3%83%BB%E3%83%A9%E3%82%A4%E3%83%B4%20%E3%82%A4%E3%83%B3%E3%83%87%E3%83%83%E3%82%AF%E3%82%B923%20Live%20at%E3%82%88%E3%81%BF%E3%81%86%E3%82%8A%E3%83%A9%E3%83%B3%E3%83%89EAST%2C%201989.4.29%20%2630)AKINA%20NAKAMORI%2FSOUTHERN%20WIND%22%2C%22artist%22%3A%22Warner%20Music%20Japan%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FiM7BvlxBpYc%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLC0lXRFOVWhll-QANClJesmc5c4XQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22iM7BvlxBpYc%22%7D%7D%7D Oct 27 17:15:06 volumio volumio[1148]: warn: [youtube2] Error getting stream with default client in VideoModel.getInfo(iM7BvlxBpYc): No valid URL to decipher - retry with 'WEB_EMBEDDED' client. Oct 27 17:15:06 volumio volumio[1148]: info: [youtube2] VideoModel.getInfo(iM7BvlxBpYc): validating stream URL "https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00"... Oct 27 17:15:06 volumio volumio[1148]: warn: [youtube2] VideoModel.getInfo(iM7BvlxBpYc): stream validation failed (403 - Forbidden); retrying after 2s... Oct 27 17:15:08 volumio volumio[1148]: warn: [youtube2] VideoModel.getInfo(iM7BvlxBpYc): stream validation failed (403 - Forbidden); retrying after 2s... Oct 27 17:15:10 volumio volumio[1148]: warn: [youtube2] VideoModel.getInfo(iM7BvlxBpYc): stream validation failed (403 - Forbidden); retrying after 2s... Oct 27 17:15:12 volumio volumio[1148]: warn: [youtube2] VideoModel.getInfo(iM7BvlxBpYc): failed to validate stream URL "https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00" (retried 3 times in 6.143s). Oct 27 17:15:12 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:12 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:15:12 volumio volumio[1148]: info: sendMpdCommand stop took 1 milliseconds Oct 27 17:15:12 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: sendMpdCommand clear took 1 milliseconds Oct 27 17:15:12 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube" Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: sendMpdCommand addid "https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube" took 3 milliseconds Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:12 volumio volumio[1148]: verbose: MPD COMMAND [object Object] Oct 27 17:15:12 volumio volumio[1148]: verbose: MPD COMMAND [object Object] Oct 27 17:15:12 volumio volumio[1148]: verbose: MPD COMMAND [object Object] Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 3ms Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:12 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:15:12 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: Oct 27 17:15:12 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:15:12 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:15:12 volumio volumio[1148]: info: sendMpdCommand play took 3 milliseconds Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:15:12 volumio volumio[1148]: info: ------------------------------ 9ms Oct 27 17:15:13 volumio volumio[1148]: info: Oct 27 17:15:13 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:13 volumio volumio[1148]: info: Oct 27 17:15:13 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:13 volumio volumio[1148]: info: Oct 27 17:15:13 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:13 volumio volumio[1148]: info: Oct 27 17:15:13 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand status took 5 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:13 volumio volumio[1148]: info: Oct 27 17:15:13 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand status took 8 milliseconds Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand status took 8 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand clearerror took 5 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand status took 5 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:13 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"⑧【公式】中森明菜／サザン・ウインド (イースト・ライヴ インデックス23 Live atよみうりランドEAST, 1989.4.29 &30)AKINA NAKAMORI/SOUTHERN WIND","artist":"Warner Music Japan","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:13 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: No code Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: ------------------------------ 24ms Oct 27 17:15:13 volumio volumio[1148]: info: Oct 27 17:15:13 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand status took 26 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand clearerror took 23 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand clearerror took 23 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand status took 7 milliseconds Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:13 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"⑧【公式】中森明菜／サザン・ウインド (イースト・ライヴ インデックス23 Live atよみうりランドEAST, 1989.4.29 &30)AKINA NAKAMORI/SOUTHERN WIND","artist":"Warner Music Japan","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:13 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: No code Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:13 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"⑧【公式】中森明菜／サザン・ウインド (イースト・ライヴ インデックス23 Live atよみうりランドEAST, 1989.4.29 &30)AKINA NAKAMORI/SOUTHERN WIND","artist":"Warner Music Japan","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:13 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: No code Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:13 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"⑧【公式】中森明菜／サザン・ウインド (イースト・ライヴ インデックス23 Live atよみうりランドEAST, 1989.4.29 &30)AKINA NAKAMORI/SOUTHERN WIND","artist":"Warner Music Japan","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:13 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: No code Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: ------------------------------ 67ms Oct 27 17:15:13 volumio volumio[1148]: info: ------------------------------ 66ms Oct 27 17:15:13 volumio volumio[1148]: info: ------------------------------ 67ms Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 27 17:15:13 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:13 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:13 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"⑧【公式】中森明菜／サザン・ウインド (イースト・ライヴ インデックス23 Live atよみうりランドEAST, 1989.4.29 &30)AKINA NAKAMORI/SOUTHERN WIND","artist":"Warner Music Japan","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:13 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: No code Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:15:13 volumio volumio[1148]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"⑧【公式】中森明菜／サザン・ウインド (イースト・ライヴ インデックス23 Live atよみうりランドEAST, 1989.4.29 &30)AKINA NAKAMORI/SOUTHERN WIND","artist":"Warner Music Japan","album":"YouTube","uri":"https://rr1---sn-3jo3poxun0-3pmk.googlevideo.com/videoplayback?expire=1761574507&ei=Cyr_aJy-DNGU1d8Pr_f9-AU&ip=117.109.223.15&id=o-ABegj91oR6e-giYF9sg6HBHi782t5oW_K-MFzx-w9LE_&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1761552907%2C&mh=gf&mm=31%2C29&mn=sn-3jo3poxun0-3pmk%2Csn-3pm76nee&ms=au%2Crdu&mv=m&mvi=1&pl=20&rms=au%2Cau&gcr=jp&initcwndbps=3945000&siu=1&bui=AdEuB5RRwPSnRKqj9wEBPOZxp0-Bz6p-K_JCfFPZYlKUd-3IDrLx9c2jCYAK1fGlEiV68wVzOA&spc=6b0G_KIwjLMa3F7BWvnEFNMk43SIGp_8DgHz_bhhccuj67NDtLsT-Vbzu94fbRufQtk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=THxQdReB33kbf6dTex2Te1oQ&rqh=1&gir=yes&clen=2688839&dur=167.961&lmt=1738278317524115&mt=1761552535&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=4532534&n=oS0ZMKtUz36tLbK&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIge3TicTAEC5G5ZiopIlZgR54lsZ6mcc4_6ejv2ZWDr9oCIQDznexKMTW1vwzO_z-keTwMjf3qGH3Vh-FlmMyrTWiugg%3D%3D&sig=JfAdSswRAIgETXRRLyz4lQ90ewrgY4SoL3gWEBRGewhY2pHpMn7OZYCIB8Mt0V3ci58xCBb8ZHG7fiRWscoDa6tw8YWRwN5&pot=MmEeoLho2OZEgx6e-pztjojxCsTjYfGPaew3IOcjsX0jQFFJxzJYwrJpWx9pEHryPhtjumKhH8ehtnDsAJjJEXsHN_116jwRZgxoVdvrRsYEj6SDSw9y4QnDS9hOoG_PDG8A&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Oct 27 17:15:13 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState stateService stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: No code Oct 27 17:15:13 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:15:13 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:15:13 volumio volumio[1148]: info: ------------------------------ 89ms Oct 27 17:15:13 volumio volumio[1148]: info: ------------------------------ 71ms Oct 27 17:15:16 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Oct 27 17:15:20 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 17:15:20 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 27 17:15:26 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 17:15:26 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Oct 27 17:15:26 volumio dbus-daemon[651]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.38' (uid=0 pid=2424 comm="timedatectl show --property=NTPSynchronized --valu") Oct 27 17:15:26 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 27 17:15:27 volumio dbus-daemon[651]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 27 17:15:27 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 27 17:15:27 volumio setdatetime-helper.sh[2423]: Time is not synchronized. Attempting to sync... Oct 27 17:15:27 volumio sudo[2432]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 27 Oct 2025 08:15:27 GMT#015' Oct 27 17:15:27 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 27 17:15:27 volumio setdatetime-helper.sh[2433]: Mon Oct 27 17:15:27 JST 2025 Oct 27 17:15:27 volumio setdatetime-helper.sh[2423]: Time synchronized successfully. Oct 27 17:15:27 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root Oct 27 17:15:27 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Oct 27 17:15:27 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Oct 27 17:15:27 volumio systemd-journald[350]: Time jumped backwards, rotating. Oct 27 17:15:42 volumio volumio[1148]: info: CALLMETHOD: music_service youtube2 configSaveAccount [object Object] Oct 27 17:15:42 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: youtube2 , configSaveAccount Oct 27 17:15:55 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 17:15:55 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 27 17:15:56 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 27 17:15:58 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Oct 27 17:16:02 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Oct 27 17:16:02 volumio volumio[1148]: info: [youtube2-browse] browseUri: youtube2 Oct 27 17:16:02 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:02 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:02 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:02 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:08 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 27 17:16:08 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:12 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 27 17:16:12 volumio volumio[1148]: info: TuneIn handleBrowseUri: tunein/local Oct 27 17:16:12 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:14 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:14 volumio volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::ClearQueue Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:14 volumio volumio[1148]: info: CorePlayQueue::clearPlayQueue Oct 27 17:16:14 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:14 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::addQueueItems Oct 27 17:16:14 volumio volumio[1148]: info: CorePlayQueue::addQueueItems Oct 27 17:16:14 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:14 volumio volumio[1148]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s284363 Oct 27 17:16:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 17:16:14 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:14 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Oct 27 17:16:14 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Oct 27 17:16:14 volumio volumio[1148]: info: CoreCommandRouter::volumioPlay Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::play index 0 Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::play index undefined Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:14 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:14 volumio volumio[1148]: info: CoreStateMachine::startPlaybackTimer Oct 27 17:16:14 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:14 volumio volumio[1148]: info: [1761552974496] ControllerWebradio::clearAddPlayTrack Oct 27 17:16:14 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:16:14 volumio volumio[1148]: info: sendMpdCommand stop took 2 milliseconds Oct 27 17:16:14 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:16:14 volumio volumio[1148]: info: sendMpdCommand clear took 1 milliseconds Oct 27 17:16:14 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand load "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:14 volumio volumio[1148]: info: Oct 27 17:16:14 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:14 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:14 volumio volumio[1148]: info: Oct 27 17:16:14 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:14 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:14 volumio volumio[1148]: info: Oct 27 17:16:14 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:14 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:16 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:16 volumio volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::ClearQueue Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:16 volumio volumio[1148]: info: CorePlayQueue::clearPlayQueue Oct 27 17:16:16 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:16 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::addQueueItems Oct 27 17:16:16 volumio volumio[1148]: info: CorePlayQueue::addQueueItems Oct 27 17:16:16 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:16 volumio volumio[1148]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s284363 Oct 27 17:16:16 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 17:16:16 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:16 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Oct 27 17:16:16 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Oct 27 17:16:16 volumio volumio[1148]: info: CoreCommandRouter::volumioPlay Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::play index 0 Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::play index undefined Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:16 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:16 volumio volumio[1148]: info: CoreStateMachine::startPlaybackTimer Oct 27 17:16:16 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:16 volumio volumio[1148]: info: [1761552976915] ControllerWebradio::clearAddPlayTrack Oct 27 17:16:16 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:16:17 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:17 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:17 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:17 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:17 volumio volumio[1148]: info: Oct 27 17:16:17 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:17 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:17 volumio volumio[1148]: info: ------------------------------ 3006ms Oct 27 17:16:17 volumio volumio[1148]: info: ------------------------------ 3006ms Oct 27 17:16:17 volumio volumio[1148]: info: ------------------------------ 3004ms Oct 27 17:16:17 volumio volumio[1148]: info: sendMpdCommand stop took 591 milliseconds Oct 27 17:16:17 volumio volumio[1148]: info: sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" took 3 milliseconds Oct 27 17:16:17 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:16:17 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:16:17 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:16:17 volumio volumio[1148]: info: Oct 27 17:16:17 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:17 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:17 volumio volumio[1148]: info: Oct 27 17:16:17 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:17 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:17 volumio volumio[1148]: info: Oct 27 17:16:17 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:17 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:17 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:17 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:17 volumio volumio[1148]: info: ------------------------------ 7ms Oct 27 17:16:17 volumio volumio[1148]: info: sendMpdCommand clear took 6 milliseconds Oct 27 17:16:17 volumio volumio[1148]: info: sendMpdCommand play took 6 milliseconds Oct 27 17:16:17 volumio volumio[1148]: info: ------------------------------ 5ms Oct 27 17:16:17 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:16:17 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand load "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:17 volumio volumio[1148]: info: Oct 27 17:16:17 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:17 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:17 volumio volumio[1148]: info: Oct 27 17:16:17 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:17 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:17 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:17 volumio volumio[1148]: info: ------------------------------ 6ms Oct 27 17:16:18 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:18 volumio volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::ClearQueue Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:18 volumio volumio[1148]: info: CorePlayQueue::clearPlayQueue Oct 27 17:16:18 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:18 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::addQueueItems Oct 27 17:16:18 volumio volumio[1148]: info: CorePlayQueue::addQueueItems Oct 27 17:16:18 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:18 volumio volumio[1148]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s284363 Oct 27 17:16:18 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 17:16:18 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:18 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Oct 27 17:16:18 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Oct 27 17:16:18 volumio volumio[1148]: info: CoreCommandRouter::volumioPlay Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::play index 0 Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::play index undefined Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:18 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:18 volumio volumio[1148]: info: CoreStateMachine::startPlaybackTimer Oct 27 17:16:18 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:18 volumio volumio[1148]: info: [1761552978189] ControllerWebradio::clearAddPlayTrack Oct 27 17:16:18 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:16:19 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:19 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:19 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:19 volumio volumio[1148]: info: Oct 27 17:16:19 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:19 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:19 volumio volumio[1148]: info: ------------------------------ 2082ms Oct 27 17:16:19 volumio volumio[1148]: info: ------------------------------ 2082ms Oct 27 17:16:19 volumio volumio[1148]: info: sendMpdCommand stop took 1408 milliseconds Oct 27 17:16:19 volumio volumio[1148]: info: sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" took 2 milliseconds Oct 27 17:16:19 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:16:19 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:16:19 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:16:19 volumio volumio[1148]: info: Oct 27 17:16:19 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:19 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:19 volumio volumio[1148]: info: Oct 27 17:16:19 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:19 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:19 volumio volumio[1148]: info: Oct 27 17:16:19 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:19 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:19 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:19 volumio volumio[1148]: info: ------------------------------ 6ms Oct 27 17:16:19 volumio volumio[1148]: info: sendMpdCommand clear took 4 milliseconds Oct 27 17:16:19 volumio volumio[1148]: info: sendMpdCommand play took 4 milliseconds Oct 27 17:16:19 volumio volumio[1148]: info: ------------------------------ 3ms Oct 27 17:16:19 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand load "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:19 volumio volumio[1148]: info: Oct 27 17:16:19 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:19 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:19 volumio volumio[1148]: info: Oct 27 17:16:19 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:19 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:19 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:19 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:19 volumio volumio[1148]: info: ------------------------------ 6ms Oct 27 17:16:19 volumio volumio[1148]: info: ------------------------------ 6ms Oct 27 17:16:20 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:20 volumio volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::ClearQueue Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:20 volumio volumio[1148]: info: CorePlayQueue::clearPlayQueue Oct 27 17:16:20 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:20 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::addQueueItems Oct 27 17:16:20 volumio volumio[1148]: info: CorePlayQueue::addQueueItems Oct 27 17:16:20 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:20 volumio volumio[1148]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s284363 Oct 27 17:16:20 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 17:16:20 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:20 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Oct 27 17:16:20 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Oct 27 17:16:20 volumio volumio[1148]: info: CoreCommandRouter::volumioPlay Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::play index 0 Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::play index undefined Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:20 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:20 volumio volumio[1148]: info: CoreStateMachine::startPlaybackTimer Oct 27 17:16:20 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:20 volumio volumio[1148]: info: [1761552980763] ControllerWebradio::clearAddPlayTrack Oct 27 17:16:20 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:16:21 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:21 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:21 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:21 volumio volumio[1148]: info: Oct 27 17:16:21 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:21 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:21 volumio volumio[1148]: info: ------------------------------ 2237ms Oct 27 17:16:21 volumio volumio[1148]: info: ------------------------------ 2235ms Oct 27 17:16:21 volumio volumio[1148]: info: sendMpdCommand stop took 1077 milliseconds Oct 27 17:16:21 volumio volumio[1148]: info: sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" took 3 milliseconds Oct 27 17:16:21 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:16:21 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:16:21 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:16:21 volumio volumio[1148]: info: Oct 27 17:16:21 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:21 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:21 volumio volumio[1148]: info: Oct 27 17:16:21 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:21 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:21 volumio volumio[1148]: info: Oct 27 17:16:21 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:21 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:21 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:21 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:21 volumio volumio[1148]: info: ------------------------------ 6ms Oct 27 17:16:21 volumio volumio[1148]: info: sendMpdCommand clear took 5 milliseconds Oct 27 17:16:21 volumio volumio[1148]: info: sendMpdCommand play took 5 milliseconds Oct 27 17:16:21 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:16:21 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:16:21 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand load "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:21 volumio volumio[1148]: info: Oct 27 17:16:21 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:21 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:21 volumio volumio[1148]: info: Oct 27 17:16:21 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:21 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:21 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:21 volumio volumio[1148]: info: ------------------------------ 7ms Oct 27 17:16:22 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:22 volumio volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::ClearQueue Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:22 volumio volumio[1148]: info: CorePlayQueue::clearPlayQueue Oct 27 17:16:22 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:22 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::addQueueItems Oct 27 17:16:22 volumio volumio[1148]: info: CorePlayQueue::addQueueItems Oct 27 17:16:22 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:22 volumio volumio[1148]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s284363 Oct 27 17:16:22 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 17:16:22 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:22 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Oct 27 17:16:22 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Oct 27 17:16:22 volumio volumio[1148]: info: CoreCommandRouter::volumioPlay Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::play index 0 Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::play index undefined Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:22 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:22 volumio volumio[1148]: info: CoreStateMachine::startPlaybackTimer Oct 27 17:16:22 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:22 volumio volumio[1148]: info: [1761552982383] ControllerWebradio::clearAddPlayTrack Oct 27 17:16:22 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:16:24 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:24 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:24 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:24 volumio volumio[1148]: info: ------------------------------ 2334ms Oct 27 17:16:24 volumio volumio[1148]: info: ------------------------------ 2333ms Oct 27 17:16:24 volumio volumio[1148]: info: sendMpdCommand stop took 1800 milliseconds Oct 27 17:16:24 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:16:24 volumio volumio[1148]: info: Oct 27 17:16:24 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:24 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:24 volumio volumio[1148]: info: sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" took 2 milliseconds Oct 27 17:16:24 volumio volumio[1148]: info: sendMpdCommand clear took 1 milliseconds Oct 27 17:16:24 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:16:24 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:16:24 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand load "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:24 volumio volumio[1148]: info: Oct 27 17:16:24 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:24 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:24 volumio volumio[1148]: info: Oct 27 17:16:24 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:24 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:24 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:24 volumio volumio[1148]: info: ------------------------------ 4ms Oct 27 17:16:24 volumio volumio[1148]: info: sendMpdCommand play took 3 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::ClearQueue Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::clearPlayQueue Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::addQueueItems Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::addQueueItems Oct 27 17:16:26 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:26 volumio volumio[1148]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25002 Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" Oct 27 17:16:26 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:26 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 2192ms Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 2192ms Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand add "https://radicrojapan.out.airtime.pro/radicrojapan_a" took 2 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 3ms Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand play took 2 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 1ms Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushQueue Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::saveQueue Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPlay Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::play index 0 Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::stop Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::play index undefined Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::startPlaybackTimer Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: info: [1761552986436] ControllerWebradio::clearAddPlayTrack Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand stop took 3 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand status took 3 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand clear took 3 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand status took 2 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand status took 2 milliseconds Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand load "https://tcc117.jp/fmyy/asx/simulbroadcast.mp3" Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 13ms Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 13ms Oct 27 17:16:26 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:26 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 13ms Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 13ms Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:16:26 volumio volumio[1148]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 16ms Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand add "https://tcc117.jp/fmyy/asx/simulbroadcast.mp3" Oct 27 17:16:26 volumio volumio[1148]: error: updateQueue error: null Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 232ms Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand add "https://tcc117.jp/fmyy/asx/simulbroadcast.mp3" took 1 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand play Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces system playlist update Oct 27 17:16:26 volumio volumio[1148]: info: Ignoring MPD Status Update Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand play took 3 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 2ms Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 1ms Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:26 volumio volumio[1148]: info: Oct 27 17:16:26 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand status took 2 milliseconds Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand status took 2 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand status took 2 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":40,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"simulbroadcast.mp3","artist":null,"album":null,"uri":"https://tcc117.jp/fmyy/asx/simulbroadcast.mp3","trackType":"mp3"} Oct 27 17:16:26 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::syncState stateService play Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus stop Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 5ms Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 27 17:16:26 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:16:26 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":40,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"simulbroadcast.mp3","artist":null,"album":null,"uri":"https://tcc117.jp/fmyy/asx/simulbroadcast.mp3","trackType":"mp3"} Oct 27 17:16:26 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::syncState stateService play Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus play Oct 27 17:16:26 volumio volumio[1148]: info: Received an update from plugin. extracting info from payload Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:26 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:26 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:26 volumio volumio[1148]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":40,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"simulbroadcast.mp3","artist":null,"album":null,"uri":"https://tcc117.jp/fmyy/asx/simulbroadcast.mp3","trackType":"mp3"} Oct 27 17:16:26 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::syncState stateService play Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus play Oct 27 17:16:26 volumio volumio[1148]: info: Received an update from plugin. extracting info from payload Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:26 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 30ms Oct 27 17:16:26 volumio volumio[1148]: info: ------------------------------ 30ms Oct 27 17:16:34 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Oct 27 17:16:34 volumio dbus-daemon[651]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.40' (uid=0 pid=2561 comm="timedatectl show --property=NTPSynchronized --valu") Oct 27 17:16:34 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 27 17:16:34 volumio dbus-daemon[651]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 27 17:16:34 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 27 17:16:34 volumio setdatetime-helper.sh[2560]: Time is not synchronized. Attempting to sync... Oct 27 17:16:34 volumio sudo[2571]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 27 Oct 2025 08:16:35 GMT#015' Oct 27 17:16:34 volumio sudo[2571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 27 17:16:35 volumio setdatetime-helper.sh[2572]: Mon Oct 27 17:16:35 JST 2025 Oct 27 17:16:35 volumio sudo[2571]: pam_unix(sudo:session): session closed for user root Oct 27 17:16:35 volumio setdatetime-helper.sh[2560]: Time synchronized successfully. Oct 27 17:16:35 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Oct 27 17:16:35 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::volumioPause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::pause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::stPlaybackTimer Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::servicePause Oct 27 17:16:40 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::servicePause Oct 27 17:16:40 volumio volumio[1148]: info: [1761553000636] ControllerWebradio::pause Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand pause Oct 27 17:16:40 volumio volumio[1148]: info: sendMpdCommand pause took 1 milliseconds Oct 27 17:16:40 volumio volumio[1148]: info: Oct 27 17:16:40 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:40 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:40 volumio volumio[1148]: info: Oct 27 17:16:40 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:40 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:40 volumio volumio[1148]: info: Oct 27 17:16:40 volumio volumio[1148]: ---------------------------- MPD announces state update: player Oct 27 17:16:40 volumio volumio[1148]: info: ControllerMpd::getState Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand status Oct 27 17:16:40 volumio volumio[1148]: info: sendMpdCommand status took 2 milliseconds Oct 27 17:16:40 volumio volumio[1148]: info: sendMpdCommand status took 2 milliseconds Oct 27 17:16:40 volumio volumio[1148]: info: sendMpdCommand status took 1 milliseconds Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::parseState Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 17:16:40 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 17:16:40 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 17:16:40 volumio volumio[1148]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:16:40 volumio volumio[1148]: verbose: ControllerMpd::parseTrackInfo Oct 27 17:16:40 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:40 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:40 volumio volumio[1148]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14328,"duration":40,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"simulbroadcast.mp3","artist":null,"album":null,"uri":"https://tcc117.jp/fmyy/asx/simulbroadcast.mp3","trackType":"mp3"} Oct 27 17:16:40 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::syncState stateService pause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus pause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::stPlaybackTimer Oct 27 17:16:40 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:40 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:40 volumio volumio[1148]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14328,"duration":40,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"simulbroadcast.mp3","artist":null,"album":null,"uri":"https://tcc117.jp/fmyy/asx/simulbroadcast.mp3","trackType":"mp3"} Oct 27 17:16:40 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::syncState stateService pause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus pause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::stPlaybackTimer Oct 27 17:16:40 volumio volumio[1148]: info: ControllerMpd::pushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::servicePushState Oct 27 17:16:40 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Oct 27 17:16:40 volumio volumio[1148]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14328,"duration":40,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"simulbroadcast.mp3","artist":null,"album":null,"uri":"https://tcc117.jp/fmyy/asx/simulbroadcast.mp3","trackType":"mp3"} Oct 27 17:16:40 volumio volumio[1148]: verbose: CURRENT POSITION 0 Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::syncState stateService pause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::syncState currentStatus pause Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::pushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Oct 27 17:16:40 volumio volumio[1148]: info: CoreStateMachine::stPlaybackTimer Oct 27 17:16:40 volumio volumio[1148]: info: ------------------------------ 19ms Oct 27 17:16:40 volumio volumio[1148]: info: ------------------------------ 18ms Oct 27 17:16:40 volumio volumio[1148]: info: ------------------------------ 18ms Oct 27 17:16:48 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 27 17:16:48 volumio volumio[1148]: info: Preload queue cleared Oct 27 17:16:51 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 27 17:16:52 volumio volumio[1148]: Use XMLElement.setAttribute instead Oct 27 17:16:52 volumio volumio[1148]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 17:16:52 volumio volumio[1148]: TypeError: children[i].attr(...).value is not a function Oct 27 17:16:52 volumio volumio[1148]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Oct 27 17:16:52 volumio volumio[1148]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 27 17:16:52 volumio volumio[1148]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 17:16:52 volumio sudo[2615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-27 17:15' Oct 27 17:16:52 volumio sudo[2615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"