Aug 31 15:15:00 volumiokwm volumio[1084]: info: [ytmusic] Preferred format is Opus Aug 31 15:15:00 volumiokwm volumio[1084]: info: CorePlayQueue::getTrack 1 Aug 31 15:15:00 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 15:15:00 volumiokwm volumio[1084]: info: sendMpdCommand stop took 1 milliseconds Aug 31 15:15:00 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: sendMpdCommand clear took 1 milliseconds Aug 31 15:15:00 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube" Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: error: updateQueue error: null Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 4ms Aug 31 15:15:00 volumiokwm volumio[1084]: info: sendMpdCommand addid "https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube" took 4 milliseconds Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 4ms Aug 31 15:15:00 volumiokwm volumio[1084]: verbose: MPD COMMAND [object Object] Aug 31 15:15:00 volumiokwm volumio[1084]: verbose: MPD COMMAND [object Object] Aug 31 15:15:00 volumiokwm volumio[1084]: verbose: MPD COMMAND [object Object] Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 12ms Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 11ms Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 10ms Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 9ms Aug 31 15:15:00 volumiokwm volumio[1084]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 15:15:00 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand play Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Aug 31 15:15:00 volumiokwm volumio[1084]: ---------------------------- MPD announces system playlist update Aug 31 15:15:00 volumiokwm volumio[1084]: info: Ignoring MPD Status Update Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 14ms Aug 31 15:15:00 volumiokwm volumio[1084]: info: sendMpdCommand play took 10 milliseconds Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 9ms Aug 31 15:15:00 volumiokwm volumio[1084]: info: ------------------------------ 6ms Aug 31 15:15:01 volumiokwm volumio[1084]: info: Aug 31 15:15:01 volumiokwm volumio[1084]: ---------------------------- MPD announces state update: player Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::getState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:15:01 volumiokwm volumio[1084]: info: Aug 31 15:15:01 volumiokwm volumio[1084]: ---------------------------- MPD announces state update: player Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::getState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:15:01 volumiokwm volumio[1084]: info: Aug 31 15:15:01 volumiokwm volumio[1084]: ---------------------------- MPD announces state update: player Aug 31 15:15:01 volumiokwm volumio[1084]: info: Aug 31 15:15:01 volumiokwm volumio[1084]: ---------------------------- MPD announces state update: player Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::getState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand status took 3 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::getState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:15:01 volumiokwm volumio[1084]: info: Aug 31 15:15:01 volumiokwm volumio[1084]: ---------------------------- MPD announces state update: player Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::getState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:15:01 volumiokwm volumio[1084]: info: Aug 31 15:15:01 volumiokwm volumio[1084]: ---------------------------- MPD announces state update: player Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::getState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand status took 8 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand status took 7 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand status took 6 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand status took 5 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::servicePushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CorePlayQueue::getTrack 1 Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落陽","artist":"吉田拓郎","album":"豊かなる一日","uri":"https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: CURRENT POSITION 1 Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState stateService play Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:15:01 volumiokwm volumio[1084]: info: ------------------------------ 18ms Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand status took 9 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseState Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::servicePushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CorePlayQueue::getTrack 1 Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落陽","artist":"吉田拓郎","album":"豊かなる一日","uri":"https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: CURRENT POSITION 1 Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState stateService play Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:15:01 volumiokwm volumio[1084]: info: Received an update from plugin. extracting info from payload Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::servicePushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CorePlayQueue::getTrack 1 Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落陽","artist":"吉田拓郎","album":"豊かなる一日","uri":"https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: CURRENT POSITION 1 Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState stateService play Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:15:01 volumiokwm volumio[1084]: info: Received an update from plugin. extracting info from payload Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::servicePushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CorePlayQueue::getTrack 1 Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落陽","artist":"吉田拓郎","album":"豊かなる一日","uri":"https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: CURRENT POSITION 1 Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState stateService play Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:15:01 volumiokwm volumio[1084]: info: Received an update from plugin. extracting info from payload Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::servicePushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CorePlayQueue::getTrack 1 Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落陽","artist":"吉田拓郎","album":"豊かなる一日","uri":"https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: CURRENT POSITION 1 Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState stateService play Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:15:01 volumiokwm volumio[1084]: info: Received an update from plugin. extracting info from payload Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: ------------------------------ 66ms Aug 31 15:15:01 volumiokwm volumio[1084]: info: ------------------------------ 65ms Aug 31 15:15:01 volumiokwm volumio[1084]: info: ------------------------------ 66ms Aug 31 15:15:01 volumiokwm volumio[1084]: info: ------------------------------ 62ms Aug 31 15:15:01 volumiokwm volumio[1084]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:15:01 volumiokwm volumio[1084]: info: ControllerMpd::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::servicePushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CorePlayQueue::getTrack 1 Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":351,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"落陽","artist":"吉田拓郎","album":"豊かなる一日","uri":"https://rr1---sn-oguelnzy.googlevideo.com/videoplayback?expire=1756642500&ei=Y-izaPauO9WXvcAPrtbdsA4&ip=221.255.129.17&id=o-AKIR85in5WHXgaqj5p7PWQpHcjsOqi4rl66UZj3aS6uz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756620899%2C&mh=DF&mm=31%2C26&mn=sn-oguelnzy%2Csn-3pm7dnel&ms=au%2Conr&mv=m&mvi=1&pl=17&rms=au%2Cau&gcr=jp&initcwndbps=3540000&bui=AY1jyLPOJZBThjS3naCdUsd9WEAzrc6Qfpfgp1eA3gvhB_a9Df6kuo_6JfQru10vWikEu8KKApMovsVP&spc=l3OVKfWOiIYaZJfIOIZOE4wkuuMyOG1SJ4w6K3EenenAyPMOrtrepZSBjU_86QYE_iw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=rtQIua6Qy6Pdgar18SBkB-EQ&rqh=1&gir=yes&clen=5645563&dur=350.681&lmt=1714572817879465&mt=1756620352&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=2318224&n=kJqY8iUo6L6cgg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgUlgBBKFX42m_eME6fK-UClauirJyYpyzmQkMQG5mwD8CIQCUEcOaiwnNP9K5HZyPrUrI0g4VvGngk6r7qmXcpVQ-vQ%3D%3D&sig=AJfQdSswRAIgdAmEdtnlQkIHWE6fTXMPM9BOhXQ3PL5-qS_SY6ToDvsCIDSEKiDOtXg0OOcxWUiRYj1AQpmiRgwlmu5ntIHfbZcw&pot=MnpaAwGpc9UUFFo9EJdtIxsln60rVFWDqdqHZzKBBvVJ_9aa5cTG2AuUlxZDmXsu05XL2aUrYeOkhbcIIGmC4lv3alJPapzONmmQlYB1qGqhTIx30-3DYmnYhccPprJGNduKX6J5wjIaDoguhKaHtFIxgyOBzDohci4HbA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:15:01 volumiokwm volumio[1084]: verbose: CURRENT POSITION 1 Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState stateService play Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:15:01 volumiokwm volumio[1084]: info: Received an update from plugin. extracting info from payload Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreStateMachine::pushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:01 volumiokwm volumio[1084]: info: ------------------------------ 75ms Aug 31 15:15:14 volumiokwm systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 31 15:16:31 volumiokwm volumio[1084]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 31 15:16:31 volumiokwm volumio[1084]: info: Preload queue cleared Aug 31 15:16:31 volumiokwm systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 31 15:16:31 volumiokwm dbus-daemon[597]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.38' (uid=0 pid=3429 comm="timedatectl show --property=NTPSynchronized --valu") Aug 31 15:16:32 volumiokwm systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 31 15:16:32 volumiokwm dbus-daemon[597]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 31 15:16:32 volumiokwm systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 31 15:16:32 volumiokwm setdatetime-helper.sh[3428]: Time is already synchronized. Aug 31 15:16:32 volumiokwm systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 31 15:16:32 volumiokwm systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 31 15:16:36 volumiokwm volumio[1084]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 31 15:16:37 volumiokwm volumio[1084]: Use XMLElement.setAttribute instead Aug 31 15:16:37 volumiokwm volumio[1084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:16:37 volumiokwm volumio[1084]: TypeError: children[i].attr(...).value is not a function Aug 31 15:16:37 volumiokwm volumio[1084]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Aug 31 15:16:37 volumiokwm volumio[1084]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 31 15:16:37 volumiokwm volumio[1084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:16:38 volumiokwm sudo[3471]: volumio : unable to resolve host volumiokwm: System error Aug 31 15:16:38 volumiokwm sudo[3471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-31 15:15' Aug 31 15:16:38 volumiokwm sudo[3471]: 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"