Jul 11 15:24:24 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 15:24:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 15:24:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 15:24:38 volumio volumio[1320]: info: Discovery: Getting this device information Jul 11 15:24:38 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Jul 11 15:24:38 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:24:38 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 15:24:40 volumio ntpd[895]: CLOCK: time stepped by 0.873363 Jul 11 15:24:40 volumio ntpd[895]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jul 11 15:24:45 volumio volumio[1320]: verbose: New Socket.io Connection to 192.168.1.128:3000 from 192.168.1.130 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jul 11 15:24:53 volumio volumio[1320]: verbose: New Socket.io Connection to 192.168.1.128:3000 from 192.168.1.130 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 11 15:24:54 volumio volumio[1320]: verbose: New Socket.io Connection to 192.168.1.128 from 192.168.1.130 UA: Mozilla/5.0 (Linux; Android 15; SM-S938B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.118 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 11 15:24:54 volumio volumio[1320]: info: Received Get System Info Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 15:24:54 volumio volumio[1320]: info: Discovery: Getting this device information Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Jul 11 15:24:54 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Jul 11 15:24:54 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Jul 11 15:24:54 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 15:24:54 volumio volumio[1320]: info: Received Get System Info Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 15:24:54 volumio volumio[1320]: info: Discovery: Getting this device information Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Jul 11 15:24:54 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Jul 11 15:24:54 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:24:54 volumio volumio[1320]: info: Listing playlists Jul 11 15:24:54 volumio volumio[1320]: info: CoreCommandRouter::volumioGetQueue Jul 11 15:24:54 volumio volumio[1320]: info: CoreStateMachine::getQueue Jul 11 15:24:54 volumio volumio[1320]: info: CorePlayQueue::getQueue Jul 11 15:24:55 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 11 15:24:56 volumio ntpd[895]: PROTO: 92.113.12.78 unlink local addr 192.168.1.128 -> Jul 11 15:25:04 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 15:25:04 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 15:25:04 volumio volumio[1320]: info: Discovery: Getting this device information Jul 11 15:25:04 volumio volumio[1320]: info: CoreCommandRouter::volumioGetState Jul 11 15:25:04 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:25:04 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 15:25:05 volumio volumio[1320]: info: CorePlayQueue::getTrack 1 Jul 11 15:25:05 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:05 volumio volumio[1320]: info: Prefetching next song Jul 11 15:25:06 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-h5nhv8pa-8ajs.googlevideo.com/videoplayback?expire=1752261906&ei=shBxaPeaB7Wtp-oPgdfTqAQ&ip=178.237.227.7&id=o-ADhhgDg8NaQSzy0EYzHIqSF1R_CwFvJR-JE2YkROdc_c&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1752240306%2C&mh=U1&mm=31%2C29&mn=sn-h5nhv8pa-8ajs%2Csn-h5q7kned&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=3226250&siu=1&bui=AY1jyLPBwR4JMUl5miEbiM0l_iHBXm5dw7d46K4W21NpL1aFWR5njdb5cQdP2-JUUiHIGhU0lA&spc=l3OVKWoIdBVIHTP70vkPfO-Gwf1_Ak4IR3dmCU40HW0ceL4_Lo3N1SK8TGzJQt7efcKriougP0GPyhqdPrA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oC1hiPMNxOVLydyeRVGWKygQ&rqh=1&gir=yes&clen=3713388&dur=227.881&lmt=1733836525761082&mt=1752239869&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mj3WUq8l9eU0sA&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=APaTxxMwRAIgYKxVJuDyl37uxVnlNPbNX_PVs_C4bv5HsxB1zgV7G6ECICkpMbp7awQpEyosG80-UVDTKH5JzQ_oAR3s6aMFUtm0&sig=AJfQdSswRQIhAIBPgUL-6bj1wU89Q9n0D9BNFfE6uuWVvqfXmSmYW8JNAiBm0-oQEA2fmuSQtTQY1dfsCc5Am6km0P_oWqUufqvQbw%3D%3D&pot=Ml9o6GVBFhXlYmjUffueEBuTjIS7_34RF9Z27O5RyTvsnLY5Wvo4FnAlTJNVbcFB3BX0BlP2CVvcH3rjNIxMSLBla3tXNR6sp-73H8wFjJxX-YPfk3Uhpg19FB_qKzBeNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Jul 11 15:25:06 volumio volumio[1320]: info: Jul 11 15:25:06 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:06 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:06 volumio volumio[1320]: info: sendMpdCommand addid "https://rr2---sn-h5nhv8pa-8ajs.googlevideo.com/videoplayback?expire=1752261906&ei=shBxaPeaB7Wtp-oPgdfTqAQ&ip=178.237.227.7&id=o-ADhhgDg8NaQSzy0EYzHIqSF1R_CwFvJR-JE2YkROdc_c&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1752240306%2C&mh=U1&mm=31%2C29&mn=sn-h5nhv8pa-8ajs%2Csn-h5q7kned&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=3226250&siu=1&bui=AY1jyLPBwR4JMUl5miEbiM0l_iHBXm5dw7d46K4W21NpL1aFWR5njdb5cQdP2-JUUiHIGhU0lA&spc=l3OVKWoIdBVIHTP70vkPfO-Gwf1_Ak4IR3dmCU40HW0ceL4_Lo3N1SK8TGzJQt7efcKriougP0GPyhqdPrA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oC1hiPMNxOVLydyeRVGWKygQ&rqh=1&gir=yes&clen=3713388&dur=227.881&lmt=1733836525761082&mt=1752239869&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mj3WUq8l9eU0sA&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=APaTxxMwRAIgYKxVJuDyl37uxVnlNPbNX_PVs_C4bv5HsxB1zgV7G6ECICkpMbp7awQpEyosG80-UVDTKH5JzQ_oAR3s6aMFUtm0&sig=AJfQdSswRQIhAIBPgUL-6bj1wU89Q9n0D9BNFfE6uuWVvqfXmSmYW8JNAiBm0-oQEA2fmuSQtTQY1dfsCc5Am6km0P_oWqUufqvQbw%3D%3D&pot=Ml9o6GVBFhXlYmjUffueEBuTjIS7_34RF9Z27O5RyTvsnLY5Wvo4FnAlTJNVbcFB3BX0BlP2CVvcH3rjNIxMSLBla3tXNR6sp-73H8wFjJxX-YPfk3Uhpg19FB_qKzBeNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 5 milliseconds Jul 11 15:25:06 volumio volumio[1320]: verbose: MPD COMMAND [object Object] Jul 11 15:25:06 volumio volumio[1320]: verbose: MPD COMMAND [object Object] Jul 11 15:25:06 volumio volumio[1320]: info: Jul 11 15:25:06 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:06 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:06 volumio volumio[1320]: info: ------------------------------ 4ms Jul 11 15:25:06 volumio volumio[1320]: info: [ytmusic-play] Prefetched and added track to MPD queue: It's a Beautiful Day Jul 11 15:25:06 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 11 15:25:06 volumio volumio[1320]: info: Jul 11 15:25:06 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:06 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:06 volumio volumio[1320]: info: Jul 11 15:25:06 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:06 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:06 volumio volumio[1320]: info: ------------------------------ 5ms Jul 11 15:25:06 volumio volumio[1320]: info: sendMpdCommand consume 1 took 4 milliseconds Jul 11 15:25:06 volumio volumio[1320]: info: ------------------------------ 3ms Jul 11 15:25:06 volumio volumio[1320]: info: ------------------------------ 3ms Jul 11 15:25:06 volumio volumio[1320]: info: Jul 11 15:25:06 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:06 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:06 volumio volumio[1320]: info: Jul 11 15:25:06 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:06 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:06 volumio volumio[1320]: info: ------------------------------ 2ms Jul 11 15:25:06 volumio volumio[1320]: info: ------------------------------ 2ms Jul 11 15:25:09 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 11 15:25:09 volumio volumio[1320]: info: Preload queue cleared Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::startPlaybackTimer Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::pushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: Jul 11 15:25:10 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:10 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:10 volumio volumio[1320]: info: Jul 11 15:25:10 volumio volumio[1320]: ---------------------------- MPD announces state update: player Jul 11 15:25:10 volumio volumio[1320]: info: ControllerMpd::getState Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Jul 11 15:25:10 volumio volumio[1320]: info: Jul 11 15:25:10 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:10 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:10 volumio volumio[1320]: info: Jul 11 15:25:10 volumio volumio[1320]: ---------------------------- MPD announces state update: player Jul 11 15:25:10 volumio volumio[1320]: info: ControllerMpd::getState Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Jul 11 15:25:10 volumio volumio[1320]: info: Jul 11 15:25:10 volumio volumio[1320]: ---------------------------- MPD announces system playlist update Jul 11 15:25:10 volumio volumio[1320]: info: Ignoring MPD Status Update Jul 11 15:25:10 volumio volumio[1320]: info: Jul 11 15:25:10 volumio volumio[1320]: ---------------------------- MPD announces state update: player Jul 11 15:25:10 volumio volumio[1320]: info: ControllerMpd::getState Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Jul 11 15:25:10 volumio volumio[1320]: info: ------------------------------ 4ms Jul 11 15:25:10 volumio volumio[1320]: info: sendMpdCommand status took 3 milliseconds Jul 11 15:25:10 volumio volumio[1320]: info: ------------------------------ 3ms Jul 11 15:25:10 volumio volumio[1320]: info: sendMpdCommand status took 2 milliseconds Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::parseState Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::parseState Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 15:25:10 volumio volumio[1320]: info: ------------------------------ 3ms Jul 11 15:25:10 volumio volumio[1320]: info: sendMpdCommand status took 3 milliseconds Jul 11 15:25:10 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 15:25:10 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::parseState Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Jul 11 15:25:10 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Jul 11 15:25:10 volumio volumio[1320]: info: ControllerMpd::pushState Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101,"duration":228,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's a Beautiful Day","artist":"Michael Bublé","album":null,"uri":"https://rr2---sn-h5nhv8pa-8ajs.googlevideo.com/videoplayback?expire=1752261906&ei=shBxaPeaB7Wtp-oPgdfTqAQ&ip=178.237.227.7&id=o-ADhhgDg8NaQSzy0EYzHIqSF1R_CwFvJR-JE2YkROdc_c&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1752240306%2C&mh=U1&mm=31%2C29&mn=sn-h5nhv8pa-8ajs%2Csn-h5q7kned&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=3226250&siu=1&bui=AY1jyLPBwR4JMUl5miEbiM0l_iHBXm5dw7d46K4W21NpL1aFWR5njdb5cQdP2-JUUiHIGhU0lA&spc=l3OVKWoIdBVIHTP70vkPfO-Gwf1_Ak4IR3dmCU40HW0ceL4_Lo3N1SK8TGzJQt7efcKriougP0GPyhqdPrA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oC1hiPMNxOVLydyeRVGWKygQ&rqh=1&gir=yes&clen=3713388&dur=227.881&lmt=1733836525761082&mt=1752239869&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mj3WUq8l9eU0sA&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=APaTxxMwRAIgYKxVJuDyl37uxVnlNPbNX_PVs_C4bv5HsxB1zgV7G6ECICkpMbp7awQpEyosG80-UVDTKH5JzQ_oAR3s6aMFUtm0&sig=AJfQdSswRQIhAIBPgUL-6bj1wU89Q9n0D9BNFfE6uuWVvqfXmSmYW8JNAiBm0-oQEA2fmuSQtTQY1dfsCc5Am6km0P_oWqUufqvQbw%3D%3D&pot=Ml9o6GVBFhXlYmjUffueEBuTjIS7_34RF9Z27O5RyTvsnLY5Wvo4FnAlTJNVbcFB3BX0BlP2CVvcH3rjNIxMSLBla3tXNR6sp-73H8wFjJxX-YPfk3Uhpg19FB_qKzBeNg%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jul 11 15:25:10 volumio volumio[1320]: verbose: CURRENT POSITION 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::syncState stateService play Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus play Jul 11 15:25:10 volumio volumio[1320]: info: Received an update from plugin. extracting info from payload Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::pushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::pushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Jul 11 15:25:10 volumio volumio[1320]: info: ControllerMpd::pushState Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101,"duration":228,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's a Beautiful Day","artist":"Michael Bublé","album":null,"uri":"https://rr2---sn-h5nhv8pa-8ajs.googlevideo.com/videoplayback?expire=1752261906&ei=shBxaPeaB7Wtp-oPgdfTqAQ&ip=178.237.227.7&id=o-ADhhgDg8NaQSzy0EYzHIqSF1R_CwFvJR-JE2YkROdc_c&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1752240306%2C&mh=U1&mm=31%2C29&mn=sn-h5nhv8pa-8ajs%2Csn-h5q7kned&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=3226250&siu=1&bui=AY1jyLPBwR4JMUl5miEbiM0l_iHBXm5dw7d46K4W21NpL1aFWR5njdb5cQdP2-JUUiHIGhU0lA&spc=l3OVKWoIdBVIHTP70vkPfO-Gwf1_Ak4IR3dmCU40HW0ceL4_Lo3N1SK8TGzJQt7efcKriougP0GPyhqdPrA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oC1hiPMNxOVLydyeRVGWKygQ&rqh=1&gir=yes&clen=3713388&dur=227.881&lmt=1733836525761082&mt=1752239869&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mj3WUq8l9eU0sA&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=APaTxxMwRAIgYKxVJuDyl37uxVnlNPbNX_PVs_C4bv5HsxB1zgV7G6ECICkpMbp7awQpEyosG80-UVDTKH5JzQ_oAR3s6aMFUtm0&sig=AJfQdSswRQIhAIBPgUL-6bj1wU89Q9n0D9BNFfE6uuWVvqfXmSmYW8JNAiBm0-oQEA2fmuSQtTQY1dfsCc5Am6km0P_oWqUufqvQbw%3D%3D&pot=Ml9o6GVBFhXlYmjUffueEBuTjIS7_34RF9Z27O5RyTvsnLY5Wvo4FnAlTJNVbcFB3BX0BlP2CVvcH3rjNIxMSLBla3tXNR6sp-73H8wFjJxX-YPfk3Uhpg19FB_qKzBeNg%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jul 11 15:25:10 volumio volumio[1320]: verbose: CURRENT POSITION 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::syncState stateService play Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus play Jul 11 15:25:10 volumio volumio[1320]: info: Received an update from plugin. extracting info from payload Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::pushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Jul 11 15:25:10 volumio volumio[1320]: info: CoreStateMachine::pushState Jul 11 15:25:10 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:10 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Jul 11 15:25:11 volumio volumio[1320]: info: ------------------------------ 33ms Jul 11 15:25:11 volumio volumio[1320]: info: ------------------------------ 33ms Jul 11 15:25:11 volumio volumio[1320]: info: sendMpdCommand playlistinfo took 28 milliseconds Jul 11 15:25:11 volumio volumio[1320]: verbose: ControllerMpd::parseTrackInfo Jul 11 15:25:11 volumio volumio[1320]: info: ControllerMpd::pushState Jul 11 15:25:11 volumio volumio[1320]: info: CoreCommandRouter::servicePushState Jul 11 15:25:11 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:11 volumio volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101,"duration":228,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's a Beautiful Day","artist":"Michael Bublé","album":null,"uri":"https://rr2---sn-h5nhv8pa-8ajs.googlevideo.com/videoplayback?expire=1752261906&ei=shBxaPeaB7Wtp-oPgdfTqAQ&ip=178.237.227.7&id=o-ADhhgDg8NaQSzy0EYzHIqSF1R_CwFvJR-JE2YkROdc_c&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1752240306%2C&mh=U1&mm=31%2C29&mn=sn-h5nhv8pa-8ajs%2Csn-h5q7kned&ms=au%2Crdu&mv=m&mvi=2&pl=21&rms=au%2Cau&initcwndbps=3226250&siu=1&bui=AY1jyLPBwR4JMUl5miEbiM0l_iHBXm5dw7d46K4W21NpL1aFWR5njdb5cQdP2-JUUiHIGhU0lA&spc=l3OVKWoIdBVIHTP70vkPfO-Gwf1_Ak4IR3dmCU40HW0ceL4_Lo3N1SK8TGzJQt7efcKriougP0GPyhqdPrA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=oC1hiPMNxOVLydyeRVGWKygQ&rqh=1&gir=yes&clen=3713388&dur=227.881&lmt=1733836525761082&mt=1752239869&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532434&n=mj3WUq8l9eU0sA&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=APaTxxMwRAIgYKxVJuDyl37uxVnlNPbNX_PVs_C4bv5HsxB1zgV7G6ECICkpMbp7awQpEyosG80-UVDTKH5JzQ_oAR3s6aMFUtm0&sig=AJfQdSswRQIhAIBPgUL-6bj1wU89Q9n0D9BNFfE6uuWVvqfXmSmYW8JNAiBm0-oQEA2fmuSQtTQY1dfsCc5Am6km0P_oWqUufqvQbw%3D%3D&pot=Ml9o6GVBFhXlYmjUffueEBuTjIS7_34RF9Z27O5RyTvsnLY5Wvo4FnAlTJNVbcFB3BX0BlP2CVvcH3rjNIxMSLBla3tXNR6sp-73H8wFjJxX-YPfk3Uhpg19FB_qKzBeNg%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jul 11 15:25:11 volumio volumio[1320]: verbose: CURRENT POSITION 2 Jul 11 15:25:11 volumio volumio[1320]: info: CoreStateMachine::syncState stateService play Jul 11 15:25:11 volumio volumio[1320]: info: CoreStateMachine::syncState currentStatus play Jul 11 15:25:11 volumio volumio[1320]: info: Received an update from plugin. extracting info from payload Jul 11 15:25:11 volumio volumio[1320]: info: CoreStateMachine::pushState Jul 11 15:25:11 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:11 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Jul 11 15:25:11 volumio volumio[1320]: info: CoreStateMachine::pushState Jul 11 15:25:11 volumio volumio[1320]: info: CorePlayQueue::getTrack 2 Jul 11 15:25:11 volumio volumio[1320]: info: CoreCommandRouter::volumioPushState Jul 11 15:25:11 volumio volumio[1320]: info: ------------------------------ 45ms Jul 11 15:25:15 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 15:25:15 volumio dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.32' (uid=0 pid=3544 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 15:25:15 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 15:25:15 volumio dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 15:25:15 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 15:25:15 volumio setdatetime-helper.sh[3541]: Time is not synchronized. Attempting to sync... Jul 11 15:25:15 volumio sudo[3552]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 11 Jul 2025 13:25:15 GMT#015' Jul 11 15:25:15 volumio sudo[3552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 15:25:15 volumio setdatetime-helper.sh[3553]: Fri Jul 11 15:25:15 CEST 2025 Jul 11 15:25:15 volumio systemd-journald[342]: Time jumped backwards, rotating. Jul 11 15:25:15 volumio sudo[3552]: pam_unix(sudo:session): session closed for user root Jul 11 15:25:15 volumio setdatetime-helper.sh[3541]: Time synchronized successfully. Jul 11 15:25:15 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 11 15:25:15 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 15:25:15 volumio volumio[1320]: Searching plugin music_service/webradio Jul 11 15:25:15 volumio volumio[1320]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 11 15:25:15 volumio volumio[1320]: Use XMLElement.setAttribute instead Jul 11 15:25:15 volumio volumio[1320]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 15:25:15 volumio volumio[1320]: TypeError: children[i].attr(...).value is not a function Jul 11 15:25:15 volumio volumio[1320]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jul 11 15:25:15 volumio volumio[1320]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jul 11 15:25:15 volumio volumio[1320]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 15:25:16 volumio sudo[3569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-11 15:24' Jul 11 15:25:16 volumio sudo[3569]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" 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 Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"