Nov 03 09:29:00 volumio-4 volumio[935]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Nov 03 09:29:00 volumio-4 volumio[935]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 03 09:29:01 volumio-4 volumio[935]: info: [ytmusic] Preferred format is Opus Nov 03 09:29:01 volumio-4 volumio[935]: info: [ytmusic] (This Life) validating stream URL "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00"... Nov 03 09:29:01 volumio-4 volumio[935]: warn: [ytmusic] (This Life) stream validation failed (403 - Forbidden); retrying after 2s... Nov 03 09:29:01 volumio-4 volumio[935]: info: MyVolumio token set successfully Nov 03 09:29:01 volumio-4 volumio[935]: info: MYVOLUMIO: Adding device Nov 03 09:29:01 volumio-4 volumio[935]: info: MYVOLUMIO: Evaluating Server Nov 03 09:29:01 volumio-4 volumio[935]: info: MyVolumio status changed Nov 03 09:29:01 volumio-4 volumio[935]: info: Streaming services startup Nov 03 09:29:01 volumio-4 volumio[935]: info: Starting Streaming Daemon Nov 03 09:29:01 volumio-4 volumio[935]: info: Removing browser output: myVolumio user plan is not superstar Nov 03 09:29:01 volumio-4 volumio[935]: info: Removing audio output: Nov 03 09:29:01 volumio-4 volumio[935]: info: Stoppping Tunnel 1 Nov 03 09:29:01 volumio-4 sudo[1389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 03 09:29:01 volumio-4 sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:29:01 volumio-4 sudo[1392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 03 09:29:01 volumio-4 sudo[1392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:29:01 volumio-4 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:29:01 volumio-4 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:29:01 volumio-4 sudo[1389]: pam_unix(sudo:session): session closed for user root Nov 03 09:29:01 volumio-4 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:29:01 volumio-4 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:29:01 volumio-4 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:29:01 volumio-4 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:29:01 volumio-4 sudo[1392]: pam_unix(sudo:session): session closed for user root Nov 03 09:29:01 volumio-4 volumio[935]: error: Cannot start Volumio Streaming Daemon Nov 03 09:29:01 volumio-4 volumio[935]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 03 09:29:01 volumio-4 volumio[935]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 03 09:29:01 volumio-4 volumio[935]: info: Remote SSH Stopped Nov 03 09:29:02 volumio-4 volumio[935]: info: Setting Geolocation for MyVolumio to eu3 Nov 03 09:29:02 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:02 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:02 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:02 volumio-4 volumio[935]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 03 09:29:03 volumio-4 volumio[935]: warn: [ytmusic] (This Life) stream validation failed (403 - Forbidden); retrying after 2s... Nov 03 09:29:03 volumio-4 volumio[935]: info: Updating MyVolumio device info Nov 03 09:29:03 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:03 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:03 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:03 volumio-4 volumio[935]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 03 09:29:04 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:04 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:04 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:29:04 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:29:05 volumio-4 volumio[935]: warn: [ytmusic] (This Life) stream validation failed (403 - Forbidden); retrying after 2s... Nov 03 09:29:06 volumio-4 volumio[935]: info: MYVOLUMIO: Adding device Nov 03 09:29:06 volumio-4 volumio[935]: info: MYVOLUMIO: Evaluating Server Nov 03 09:29:07 volumio-4 volumio[935]: info: Setting Geolocation for MyVolumio to eu8 Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:07 volumio-4 volumio[935]: warn: [ytmusic] (This Life) failed to validate stream URL "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00" (retried 3 times in 6.106s). Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand stop took 2 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand clear took 1 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: error: updateQueue error: null Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 5ms Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand addid "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube" took 5 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 5ms Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 4ms Nov 03 09:29:07 volumio-4 volumio[935]: verbose: MPD COMMAND [object Object] Nov 03 09:29:07 volumio-4 volumio[935]: verbose: MPD COMMAND [object Object] Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 5ms Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 2ms Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 2ms Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand play Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:07 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 3ms Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand play took 3 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 3ms Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 3ms Nov 03 09:29:07 volumio-4 volumio[935]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 3 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 3 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 3 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 3 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: info: Nov 03 09:29:07 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 6 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 6 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 5 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 5 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 4 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 4 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 4 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:07 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:07 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: No code Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:07 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:07 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: No code Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:07 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:07 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: No code Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 22ms Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 21ms Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 21ms Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand status took 19 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 17 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 17 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:07 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:07 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: No code Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:07 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:07 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: No code Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 31ms Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 30ms Nov 03 09:29:07 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 03 09:29:07 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:07 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:07 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180140&ei=zGcIad62L_LLi9oPv86wCQ&ip=90.91.164.42&id=o-AOOTdO9ffHqcEZlEeHjQLEJVy18sd2d2m8LlrMWsHflN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=96&met=1762158540%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2650000&bui=AdEuB5SQsC9tSJniDu9PHUPkgNSU-63xeZbp89Bn9itXK9NBnUaC0v5Vqck4-Jkfi5LF3WAbeJGbHYoG&spc=6b0G_DEVXZAZ83Q-zPoJqnkKb5qrut1T7VW2LoiKmWpcBpJUv7DQ9l6SW0VKktFggrk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IGCMZd02LQjQ_GcdNiPZxfAQ&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=H8UKtSMRTne5h9T&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgPmg75YcL03kLndMdDeOmpdnwlEG46jsapmK4jstwwp4CICPJb9CPiL5DqalEqbHXkkw8M1tmqMAvPbclkRztjCX5&sig=Iz4d3JTLS20wDnQyZf5FR1B382PLn9Z4QI6XAya4dhjHZC4DrwzNB8TIfSpEMwWUKqpKXAwNl7dzh7wj4RaaBaNtQgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:07 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: No code Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:07 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:07 volumio-4 volumio[935]: info: ------------------------------ 30ms Nov 03 09:29:08 volumio-4 volumio[935]: info: Updating MyVolumio device info Nov 03 09:29:08 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:08 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:08 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:08 volumio-4 volumio[935]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 03 09:29:10 volumio-4 systemd[1]: systemd-fsckd.service: Deactivated successfully. Nov 03 09:29:12 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 09:29:12 volumio-4 volumio[935]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Nov 03 09:29:12 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:14 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 09:29:17 volumio-4 volumio-remote-updater[665]: Test mode enabled Nov 03 09:29:17 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 03 09:29:17 volumio-4 volumio-remote-updater[665]: Alpha mode disabled Nov 03 09:29:17 volumio-4 volumio-remote-updater[665]: Alpha legacy test mode disabled Nov 03 09:29:18 volumio-4 volumio[935]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 03 09:29:18 volumio-4 volumio[935]: info: Completed starting MyVolumio Plugin Nov 03 09:29:18 volumio-4 volumio[935]: [Metrics] CommandRouter: 34s 448.09ms Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::volumiosetStartupVolume Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:18 volumio-4 volumio[935]: info: VolumeController:: Setting startup Volume 60 Nov 03 09:29:18 volumio-4 volumio[935]: info: VolumeController::SetAlsaVolume60 Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::Close All Modals sent Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::Close All Modals sent Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:18 volumio-4 volumio[935]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Nov 03 09:29:18 volumio-4 volumio[935]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 09:29:18 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 09:29:19 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 03 09:29:19 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 09:29:19 volumio-4 volumio[935]: aplay: device_list:274: no soundcards found... Nov 03 09:29:19 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 03 09:29:19 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 03 09:29:24 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:24 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:29:24 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:29:25 volumio-4 volumio[935]: info: BOOT COMPLETED Nov 03 09:29:34 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:37 volumio-4 kernel: usb 2-1: new full-speed USB device number 2 using xhci_hcd Nov 03 09:29:37 volumio-4 kernel: usb 2-1: New USB device found, idVendor=08bb, idProduct=2706, bcdDevice= 1.00 Nov 03 09:29:37 volumio-4 kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Nov 03 09:29:37 volumio-4 kernel: usb 2-1: Product: USB Audio DAC Nov 03 09:29:37 volumio-4 kernel: usb 2-1: Manufacturer: Burr-Brown from TI Nov 03 09:29:37 volumio-4 kernel: input: Burr-Brown from TI USB Audio DAC as /devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb2/2-1/2-1:1.2/0003:08BB:2706.0002/input/input6 Nov 03 09:29:37 volumio-4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Nov 03 09:29:37 volumio-4 kernel: hid-generic 0003:08BB:2706.0002: input,hidraw1: USB HID v1.00 Device [Burr-Brown from TI USB Audio DAC ] on usb-0000:02:1b.0-1/input2 Nov 03 09:29:37 volumio-4 kernel: usbcore: registered new interface driver snd-usb-audio Nov 03 09:29:37 volumio-4 systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Nov 03 09:29:37 volumio-4 volumio[935]: info: Nov 03 09:29:37 volumio-4 volumio[935]: ---------------------------- USB Audio Device Attached Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 03 09:29:37 volumio-4 systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::Close All Modals sent Nov 03 09:29:37 volumio-4 volumio[935]: info: Preparing to save Alsa Options, stopping services first Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:37 volumio-4 volumio[935]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"USB Audio DAC","alsacard":"DAC"},"i2s":false} Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 09:29:37 volumio-4 systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Nov 03 09:29:37 volumio-4 systemd[1]: Reached target sound.target - Sound Card. Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 09:29:37 volumio-4 volumio[935]: info: Setting mixer PCM for card USB Audio DAC Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 03 09:29:37 volumio-4 volumio[935]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio DAC Mixer: PCM Max Vol: 90 Vol Curve; logarithmic Vol Steps: 5 Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 03 09:29:37 volumio-4 volumio[935]: info: Disabling external Volume Control Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 09:29:37 volumio-4 volumio[935]: info: Preparing to generate the ALSA configuration file Nov 03 09:29:37 volumio-4 volumio[935]: info: Asound.conf file unchanged, so no further update is needed Nov 03 09:29:37 volumio-4 volumio[935]: info: Output device has changed, restarting MPD Nov 03 09:29:37 volumio-4 sudo[1575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 09:29:37 volumio-4 sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:29:37 volumio-4 sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 09:29:37 volumio-4 sudo[1575]: pam_unix(sudo:session): session closed for user root Nov 03 09:29:37 volumio-4 sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 09:29:37 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 03 09:29:37 volumio-4 volumio[935]: info: VolumeController:: Volume=43 Mute =false Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:37 volumio-4 volumio[935]: info: MPD Permissions set Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:29:37 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Nov 03 09:29:37 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 03 09:29:37 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Nov 03 09:29:37 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 03 09:29:37 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 03 09:29:37 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 03 09:29:37 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 03 09:29:37 volumio-4 sudo[1595]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 09:29:37 volumio-4 sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 03 09:29:37 volumio-4 sudo[1595]: pam_unix(sudo:session): session closed for user root Nov 03 09:29:37 volumio-4 mpd[1597]: 2025-11-03T09:29:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 03 09:29:37 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Nov 03 09:29:37 volumio-4 sudo[1576]: pam_unix(sudo:session): session closed for user root Nov 03 09:29:37 volumio-4 volumio[935]: error: updateQueue error: null Nov 03 09:29:37 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Nov 03 09:29:40 volumio-4 sudo[1604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 03 09:29:40 volumio-4 sudo[1604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:29:40 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Nov 03 09:29:40 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Nov 03 09:29:40 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Nov 03 09:29:40 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Nov 03 09:29:40 volumio-4 sudo[1604]: pam_unix(sudo:session): session closed for user root Nov 03 09:29:40 volumio-4 mpd_monitor.sh[1606]: MPD Monitor Service: Starting MPD Monitor Service Nov 03 09:29:40 volumio-4 volumio[935]: info: Successfully started MPD Monitor Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreStateMachine::getcurrentVolume Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 09:29:41 volumio-4 volumio[935]: info: VolumeController:: Volume=43 Mute =false Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreStateMachine::updateTrackBlock Nov 03 09:29:41 volumio-4 volumio[935]: info: CorePlayQueue::getTrackBlock Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 09:29:41 volumio-4 volumio[935]: info: VolumeController:: Volume=43 Mute =false Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetQueue Nov 03 09:29:41 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:41 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:42 volumio-4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Nov 03 09:29:42 volumio-4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Nov 03 09:29:43 volumio-4 volumio[935]: info: Preload queue cleared Nov 03 09:29:43 volumio-4 volumio[935]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 09:29:43 volumio-4 volumio[935]: info: CoreStateMachine::ClearQueue Nov 03 09:29:43 volumio-4 volumio[935]: info: CoreStateMachine::stop Nov 03 09:29:43 volumio-4 volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 09:29:43 volumio-4 volumio[935]: info: CorePlayQueue::clearPlayQueue Nov 03 09:29:43 volumio-4 volumio[935]: info: CorePlayQueue::saveQueue Nov 03 09:29:43 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushQueue Nov 03 09:29:43 volumio-4 volumio[935]: info: CoreStateMachine::addQueueItems Nov 03 09:29:43 volumio-4 volumio[935]: info: CorePlayQueue::addQueueItems Nov 03 09:29:43 volumio-4 volumio[935]: info: Preload queue cleared Nov 03 09:29:43 volumio-4 volumio[935]: info: Adding Item to queue: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres_category%22%2C%22params%22%3A%22ggMPOg1uXzZQbDB5eThLRTQ3%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%2C%22params%22%3A%22wAEB%22%7D%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22VLRDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D Nov 03 09:29:43 volumio-4 volumio[935]: info: Exploding uri ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres_category%22%2C%22params%22%3A%22ggMPOg1uXzZQbDB5eThLRTQ3%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%2C%22params%22%3A%22wAEB%22%7D%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22VLRDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D in service ytmusic Nov 03 09:29:43 volumio-4 volumio[935]: info: [ytmusic-browse] explodeUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres%22%7D%7D/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_moods_and_genres_category%22%2C%22params%22%3A%22ggMPOg1uXzZQbDB5eThLRTQ3%22%7D%7D/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%2C%22params%22%3A%22wAEB%22%7D%7D%2C%22browse%22%3A%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22VLRDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushQueue Nov 03 09:29:44 volumio-4 volumio[935]: info: CorePlayQueue::saveQueue Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::updateTrackBlock Nov 03 09:29:44 volumio-4 volumio[935]: info: CorePlayQueue::getTrackBlock Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPlay Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::play index 0 Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::stop Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::play index undefined Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 09:29:44 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreStateMachine::startPlaybackTimer Nov 03 09:29:44 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:44 volumio-4 volumio[935]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22This%20Life%22%2C%22artist%22%3A%22Vampire%20Weekend%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FFwkrrU2WYKg%2Fhq720.jpg%3Fsqp%3D-oaymwEXCNUGEOADIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3lUh0pkSPbZRFsNSHTzfJDu2rxNoQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22FwkrrU2WYKg%22%2C%22playlistId%22%3A%22RDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%2C%22params%22%3A%22OAHyAQQIARgD%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%2256B44F6D10557CC6%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_kJWGcrtTC_zrbD6rKkBvOcht_vzijhX1A%22%2C%22params%22%3A%22OAHyAQQIARgD%22%2C%22videoId%22%3A%22z0wT6CrEGYg%22%7D%7D%7D%7D Nov 03 09:29:44 volumio-4 volumio[935]: info: [ytmusic] Preferred format is Opus Nov 03 09:29:44 volumio-4 volumio[935]: info: [ytmusic] (This Life) validating stream URL "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180184&ei=-GcIafu7DYzGi9oPxYra2QM&ip=90.91.164.42&id=o-AB4kdashwQm1SvzcD2J-XEL3RMgM9moP4e18WDI1SKeQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762158584%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2631250&bui=AdEuB5TuSNuUFDilTHZajxJbrPCxAGxNvJg1Z5ayAwU-0ali5GikSsMn5HQoGkv_dDAOpcNp2AYuAmRa&spc=6b0G_E4yMQNlHovmOIScbwxzBImLj7RUPH7FUxDVK3bWUTIxmalLn4mW270ExoKCqEg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Eh-IIf6ZrCcfMMK03oDlTJ0Q&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=nQQvRl9DZRYzNMZ&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=APaTxxMwRAIgCzVMk8kWIB9YWYqgdMeAE1P9YVgXUK9jxqFjzKOMRkoCIEEsTG3OwgCDu_c0n_oHt-beplrZYzITaWOUsFkji_0G&sig=Iqo3wMdyq2Q4Sr3AxPuHHTdpX6BXKJTmPm4xoTSIvqDH1CQZWiqAElWINFXG5ziSZlhurzmdLZC3RBYg4krXMYJYYgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00"... Nov 03 09:29:44 volumio-4 volumio[935]: warn: [ytmusic] (This Life) stream validation failed (403 - Forbidden); retrying after 2s... Nov 03 09:29:44 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:44 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:44 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:29:44 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:29:46 volumio-4 volumio[935]: warn: [ytmusic] (This Life) stream validation failed (403 - Forbidden); retrying after 2s... Nov 03 09:29:48 volumio-4 volumio[935]: warn: [ytmusic] (This Life) stream validation failed (403 - Forbidden); retrying after 2s... Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:50 volumio-4 volumio[935]: warn: [ytmusic] (This Life) failed to validate stream URL "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180184&ei=-GcIafu7DYzGi9oPxYra2QM&ip=90.91.164.42&id=o-AB4kdashwQm1SvzcD2J-XEL3RMgM9moP4e18WDI1SKeQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762158584%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2631250&bui=AdEuB5TuSNuUFDilTHZajxJbrPCxAGxNvJg1Z5ayAwU-0ali5GikSsMn5HQoGkv_dDAOpcNp2AYuAmRa&spc=6b0G_E4yMQNlHovmOIScbwxzBImLj7RUPH7FUxDVK3bWUTIxmalLn4mW270ExoKCqEg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Eh-IIf6ZrCcfMMK03oDlTJ0Q&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=nQQvRl9DZRYzNMZ&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=APaTxxMwRAIgCzVMk8kWIB9YWYqgdMeAE1P9YVgXUK9jxqFjzKOMRkoCIEEsTG3OwgCDu_c0n_oHt-beplrZYzITaWOUsFkji_0G&sig=Iqo3wMdyq2Q4Sr3AxPuHHTdpX6BXKJTmPm4xoTSIvqDH1CQZWiqAElWINFXG5ziSZlhurzmdLZC3RBYg4krXMYJYYgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00" (retried 3 times in 6.1s). Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand stop took 1 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 09:29:50 volumio-4 volumio[935]: info: Nov 03 09:29:50 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:50 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand clear took 0 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180184&ei=-GcIafu7DYzGi9oPxYra2QM&ip=90.91.164.42&id=o-AB4kdashwQm1SvzcD2J-XEL3RMgM9moP4e18WDI1SKeQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762158584%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2631250&bui=AdEuB5TuSNuUFDilTHZajxJbrPCxAGxNvJg1Z5ayAwU-0ali5GikSsMn5HQoGkv_dDAOpcNp2AYuAmRa&spc=6b0G_E4yMQNlHovmOIScbwxzBImLj7RUPH7FUxDVK3bWUTIxmalLn4mW270ExoKCqEg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Eh-IIf6ZrCcfMMK03oDlTJ0Q&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=nQQvRl9DZRYzNMZ&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=APaTxxMwRAIgCzVMk8kWIB9YWYqgdMeAE1P9YVgXUK9jxqFjzKOMRkoCIEEsTG3OwgCDu_c0n_oHt-beplrZYzITaWOUsFkji_0G&sig=Iqo3wMdyq2Q4Sr3AxPuHHTdpX6BXKJTmPm4xoTSIvqDH1CQZWiqAElWINFXG5ziSZlhurzmdLZC3RBYg4krXMYJYYgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 03 09:29:50 volumio-4 volumio[935]: error: updateQueue error: null Nov 03 09:29:50 volumio-4 volumio[935]: info: Nov 03 09:29:50 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:50 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:50 volumio-4 volumio[935]: info: ------------------------------ 2ms Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand addid "https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180184&ei=-GcIafu7DYzGi9oPxYra2QM&ip=90.91.164.42&id=o-AB4kdashwQm1SvzcD2J-XEL3RMgM9moP4e18WDI1SKeQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762158584%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2631250&bui=AdEuB5TuSNuUFDilTHZajxJbrPCxAGxNvJg1Z5ayAwU-0ali5GikSsMn5HQoGkv_dDAOpcNp2AYuAmRa&spc=6b0G_E4yMQNlHovmOIScbwxzBImLj7RUPH7FUxDVK3bWUTIxmalLn4mW270ExoKCqEg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Eh-IIf6ZrCcfMMK03oDlTJ0Q&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=nQQvRl9DZRYzNMZ&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=APaTxxMwRAIgCzVMk8kWIB9YWYqgdMeAE1P9YVgXUK9jxqFjzKOMRkoCIEEsTG3OwgCDu_c0n_oHt-beplrZYzITaWOUsFkji_0G&sig=Iqo3wMdyq2Q4Sr3AxPuHHTdpX6BXKJTmPm4xoTSIvqDH1CQZWiqAElWINFXG5ziSZlhurzmdLZC3RBYg4krXMYJYYgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube" took 2 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: MPD COMMAND [object Object] Nov 03 09:29:50 volumio-4 volumio[935]: verbose: MPD COMMAND [object Object] Nov 03 09:29:50 volumio-4 volumio[935]: info: ------------------------------ 2ms Nov 03 09:29:50 volumio-4 volumio[935]: info: Nov 03 09:29:50 volumio-4 volumio[935]: ---------------------------- MPD announces system playlist update Nov 03 09:29:50 volumio-4 volumio[935]: info: Ignoring MPD Status Update Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand play Nov 03 09:29:50 volumio-4 volumio[935]: info: ------------------------------ 2ms Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand play took 3 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:50 volumio-4 volumio[935]: info: Nov 03 09:29:50 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:50 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:50 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:50 volumio-4 volumio[935]: info: Nov 03 09:29:50 volumio-4 volumio[935]: ---------------------------- MPD announces state update: player Nov 03 09:29:50 volumio-4 volumio[935]: info: ControllerMpd::getState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand status Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand status took 2 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand status took 3 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand status took 3 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand status took 3 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 3 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseState Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 4 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 4 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand clearerror took 3 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:50 volumio-4 volumio[935]: verbose: ControllerMpd::parseTrackInfo Nov 03 09:29:50 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:50 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180184&ei=-GcIafu7DYzGi9oPxYra2QM&ip=90.91.164.42&id=o-AB4kdashwQm1SvzcD2J-XEL3RMgM9moP4e18WDI1SKeQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762158584%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2631250&bui=AdEuB5TuSNuUFDilTHZajxJbrPCxAGxNvJg1Z5ayAwU-0ali5GikSsMn5HQoGkv_dDAOpcNp2AYuAmRa&spc=6b0G_E4yMQNlHovmOIScbwxzBImLj7RUPH7FUxDVK3bWUTIxmalLn4mW270ExoKCqEg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Eh-IIf6ZrCcfMMK03oDlTJ0Q&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=nQQvRl9DZRYzNMZ&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=APaTxxMwRAIgCzVMk8kWIB9YWYqgdMeAE1P9YVgXUK9jxqFjzKOMRkoCIEEsTG3OwgCDu_c0n_oHt-beplrZYzITaWOUsFkji_0G&sig=Iqo3wMdyq2Q4Sr3AxPuHHTdpX6BXKJTmPm4xoTSIvqDH1CQZWiqAElWINFXG5ziSZlhurzmdLZC3RBYg4krXMYJYYgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:50 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:50 volumio-4 volumio[935]: info: No code Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getQueue Nov 03 09:29:50 volumio-4 volumio[935]: info: ControllerMpd::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::servicePushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CorePlayQueue::getTrack 0 Nov 03 09:29:50 volumio-4 volumio[935]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Life","artist":"Vampire Weekend","album":null,"uri":"https://rr4---sn-25glenld.googlevideo.com/videoplayback?expire=1762180184&ei=-GcIafu7DYzGi9oPxYra2QM&ip=90.91.164.42&id=o-AB4kdashwQm1SvzcD2J-XEL3RMgM9moP4e18WDI1SKeQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762158584%2C&mh=HU&mm=31%2C26&mn=sn-25glenld%2Csn-4g5ednkz&ms=au%2Conr&mv=m&mvi=4&pl=19&rms=au%2Cau&gcr=fr&initcwndbps=2631250&bui=AdEuB5TuSNuUFDilTHZajxJbrPCxAGxNvJg1Z5ayAwU-0ali5GikSsMn5HQoGkv_dDAOpcNp2AYuAmRa&spc=6b0G_E4yMQNlHovmOIScbwxzBImLj7RUPH7FUxDVK3bWUTIxmalLn4mW270ExoKCqEg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Eh-IIf6ZrCcfMMK03oDlTJ0Q&rqh=1&gir=yes&clen=5106270&dur=323.021&lmt=1714581647363622&mt=1762158061&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=4502434&n=nQQvRl9DZRYzNMZ&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=APaTxxMwRAIgCzVMk8kWIB9YWYqgdMeAE1P9YVgXUK9jxqFjzKOMRkoCIEEsTG3OwgCDu_c0n_oHt-beplrZYzITaWOUsFkji_0G&sig=Iqo3wMdyq2Q4Sr3AxPuHHTdpX6BXKJTmPm4xoTSIvqDH1CQZWiqAElWINFXG5ziSZlhurzmdLZC3RBYg4krXMYJYYgIARwsSdQ&pot=MpoBlh6ArCbu3xaWIPEpJGDv95u5myxOPcA9IZWjzFKRZNdZi1FPT-NOZbH6xoE-xjAYp14hP9vCs36BNvoQyQOBnnE96i2fzvVv-NlmMnpkixefclh0f875k-Vja5osAQ9h4QMPBKFQvBvqilCML02mrBtKTp5x1Kh4LBczrXA6SJW_bzWhBY_rcM6NogPMuuV1vdXufQTpLSG18A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 03 09:29:50 volumio-4 volumio[935]: verbose: CURRENT POSITION 0 Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::syncState stateService stop Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::syncState currentStatus stop Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:50 volumio-4 volumio[935]: info: No code Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreStateMachine::pushState Nov 03 09:29:50 volumio-4 volumio[935]: info: CoreCommandRouter::volumioPushState Nov 03 09:29:50 volumio-4 volumio[935]: info: ------------------------------ 18ms Nov 03 09:29:50 volumio-4 volumio[935]: info: ------------------------------ 18ms Nov 03 09:29:54 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:30:03 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:30:04 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:30:04 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:30:04 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:30:05 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 09:30:05 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 09:30:14 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:30:18 volumio-4 volumio[935]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/amd64 Nov 03 09:30:19 volumio-4 volumio[935]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/amd64 Nov 03 09:30:19 volumio-4 volumio[935]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music Nov 03 09:30:19 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 09:30:19 volumio-4 volumio[935]: Cannot find translation for source YouTube2 Nov 03 09:30:19 volumio-4 volumio[935]: Cannot find translation for source Volusonic Nov 03 09:30:20 volumio-4 volumio[935]: info: Check plugin dependencies Nov 03 09:30:20 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 09:30:20 volumio-4 volumio[935]: info: Rename folder Nov 03 09:30:20 volumio-4 volumio[935]: info: Folder /tmp/downloaded_plugin.zip removed Nov 03 09:30:20 volumio-4 volumio[935]: info: Move to category Nov 03 09:30:20 volumio-4 volumio[935]: info: Checking if install.sh is present Nov 03 09:30:20 volumio-4 volumio[935]: info: Executing install.sh Nov 03 09:30:20 volumio-4 sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh Nov 03 09:30:20 volumio-4 sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:30:20 volumio-4 sudo[1749]: pam_unix(sudo:session): session closed for user root Nov 03 09:30:20 volumio-4 volumio[935]: info: YouTube Music plugin installed Nov 03 09:30:20 volumio-4 volumio[935]: Plugin install end detected on script Nov 03 09:30:20 volumio-4 volumio[935]: info: Install script completed Nov 03 09:30:20 volumio-4 volumio[935]: info: Done installing plugin. Nov 03 09:30:20 volumio-4 volumio[935]: info: Enabling plugin ytmusic Nov 03 09:30:20 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 09:30:20 volumio-4 volumio[935]: info: [{"prettyName":"Volusonic","name":"volusonic","category":"music_service","version":"3.3.0","icon":"fa-ship","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"2.3.6","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"2.3.5","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"4.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true}] Nov 03 09:30:20 volumio-4 volumio[935]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 09:30:20 volumio-4 volumio[935]: info: Folder /tmp/plugins removed Nov 03 09:30:20 volumio-4 volumio[935]: info: Folder /tmp/downloaded_plugin.zip removed Nov 03 09:30:20 volumio-4 volumio[935]: info: Folder /data/temp removed Nov 03 09:30:24 volumio-4 volumio[935]: info: CoreCommandRouter::volumioGetState Nov 03 09:30:24 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:30:24 volumio-4 volumio[935]: info: Listing playlists Nov 03 09:30:32 volumio-4 volumio[935]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 03 09:30:32 volumio-4 volumio[935]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 09:30:32 volumio-4 volumio[935]: TypeError: Cannot read properties of null (reading 'logger') Nov 03 09:30:32 volumio-4 volumio[935]: at YTMusicContext.getLogger (/data/plugins/music_service/ytmusic/dist/lib/YTMusicContext.js:65:80) Nov 03 09:30:32 volumio-4 volumio[935]: at /data/plugins/music_service/ytmusic/dist/index.js:140:38 Nov 03 09:30:32 volumio-4 volumio[935]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Nov 03 09:30:32 volumio-4 volumio[935]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 09:30:32 volumio-4 sudo[1794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-03 09:29' Nov 03 09:30:32 volumio-4 sudo[1794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:33:08 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="434a4f7d2e5f6acafabfcca26b8e5834"