Aug 31 15:36:06 volumiokwm systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 31 15:36:06 volumiokwm dbus-daemon[573]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.30' (uid=0 pid=2650 comm="timedatectl show --property=NTPSynchronized --valu") Aug 31 15:36:06 volumiokwm systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 31 15:36:06 volumiokwm dbus-daemon[573]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 31 15:36:06 volumiokwm systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 31 15:36:06 volumiokwm setdatetime-helper.sh[2649]: Time is not synchronized. Attempting to sync... Aug 31 15:36:06 volumiokwm sudo[2658]: root : unable to resolve host volumiokwm: System error Aug 31 15:36:06 volumiokwm setdatetime-helper.sh[2658]: sudo: unable to resolve host volumiokwm: System error Aug 31 15:36:06 volumiokwm sudo[2658]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 31 Aug 2025 06:36:06 GMT#015' Aug 31 15:36:06 volumiokwm sudo[2658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 15:36:06 volumiokwm setdatetime-helper.sh[2659]: Sun Aug 31 15:36:06 JST 2025 Aug 31 15:36:06 volumiokwm systemd-journald[333]: Time jumped backwards, rotating. Aug 31 15:36:06 volumiokwm setdatetime-helper.sh[2649]: Time synchronized successfully. Aug 31 15:36:06 volumiokwm sudo[2658]: pam_unix(sudo:session): session closed for user root Aug 31 15:36:06 volumiokwm systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 31 15:36:06 volumiokwm systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 31 15:36:11 volumiokwm volumio[1080]: Searching plugin music_service/ytmusic Aug 31 15:36:11 volumiokwm volumio[1080]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Aug 31 15:36:12 volumiokwm volumio[1080]: info: All search sources collected, pushing search results Aug 31 15:36:35 volumiokwm systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::ClearQueue Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::stPlaybackTimer Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::updateTrackBlock Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrackBlock Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::serviceStop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::serviceStop Aug 31 15:36:55 volumiokwm volumio[1080]: info: [1756622215053] ControllerCalmRadio::stop Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::clearPlayQueue Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::saveQueue Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushQueue Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::addQueueItems Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::addQueueItems Aug 31 15:36:55 volumiokwm volumio[1080]: info: Preload queue cleared Aug 31 15:36:55 volumiokwm volumio[1080]: info: Adding Item to queue: ytmusic/search@query=lute/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL183934EE804B8041%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%22VLPL183934EE804B8041%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D Aug 31 15:36:55 volumiokwm volumio[1080]: info: Exploding uri ytmusic/search@query=lute/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL183934EE804B8041%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%22VLPL183934EE804B8041%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D in service ytmusic Aug 31 15:36:55 volumiokwm volumio[1080]: info: [ytmusic-browse] explodeUri: ytmusic/search@query=lute/playlist@endpoints:o=%7B%22watch%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL183934EE804B8041%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%22VLPL183934EE804B8041%22%7D%2C%22pageType%22%3A%22MUSIC_PAGE_TYPE_PLAYLIST%22%7D%7D Aug 31 15:36:55 volumiokwm volumio[1080]: info: Aug 31 15:36:55 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:55 volumiokwm volumio[1080]: info: sendMpdCommand stop took 49 milliseconds Aug 31 15:36:55 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:55 volumiokwm volumio[1080]: info: Aug 31 15:36:55 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:55 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:55 volumiokwm volumio[1080]: info: Aug 31 15:36:55 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:55 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:55 volumiokwm volumio[1080]: info: sendMpdCommand status took 7 milliseconds Aug 31 15:36:55 volumiokwm volumio[1080]: info: sendMpdCommand status took 7 milliseconds Aug 31 15:36:55 volumiokwm volumio[1080]: info: sendMpdCommand status took 5 milliseconds Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:55 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 15:36:55 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 15:36:55 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:55 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: 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":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"CALMRADIO.COM - Sleep Channel","album":null,"uri":"https://streams.calmradio.com/api/39/128/stream","trackType":""} Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: No code Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: 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":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"CALMRADIO.COM - Sleep Channel","album":null,"uri":"https://streams.calmradio.com/api/39/128/stream","trackType":""} Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: No code Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: 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":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"CALMRADIO.COM - Sleep Channel","album":null,"uri":"https://streams.calmradio.com/api/39/128/stream","trackType":""} Aug 31 15:36:55 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: No code Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:55 volumiokwm volumio[1080]: info: ------------------------------ 130ms Aug 31 15:36:55 volumiokwm volumio[1080]: info: ------------------------------ 129ms Aug 31 15:36:55 volumiokwm volumio[1080]: info: ------------------------------ 131ms Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushQueue Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::saveQueue Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::updateTrackBlock Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrackBlock Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPlay Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::play index 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::stop Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::play index undefined Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: CoreStateMachine::startPlaybackTimer Aug 31 15:36:55 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:55 volumiokwm volumio[1080]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Suite%20for%20lute%20in%20G%20minor%2C%20BWV%20995%20-%201%2F2%22%2C%22artist%22%3A%221600to1835%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fex6nDJlH_1U%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3l8Ak6My_1c64GRlWJ7tdhtTHghOQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22ex6nDJlH_1U%22%2C%22playlistId%22%3A%22PL183934EE804B8041%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%222793FF26D16F4B44%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PL183934EE804B8041%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22videoId%22%3A%22-QP96WhAKKU%22%7D%7D%7D%7D Aug 31 15:36:56 volumiokwm volumio[1080]: info: [ytmusic] Preferred format is Opus Aug 31 15:36:56 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:56 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 15:36:56 volumiokwm volumio[1080]: info: sendMpdCommand stop took 2 milliseconds Aug 31 15:36:56 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 15:36:56 volumiokwm volumio[1080]: info: sendMpdCommand clear took 1 milliseconds Aug 31 15:36:56 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube" Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: sendMpdCommand addid "https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube" took 8 milliseconds Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 7ms Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 6ms Aug 31 15:36:56 volumiokwm volumio[1080]: verbose: MPD COMMAND [object Object] Aug 31 15:36:56 volumiokwm volumio[1080]: verbose: MPD COMMAND [object Object] Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 12ms Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 11ms Aug 31 15:36:56 volumiokwm volumio[1080]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 15:36:56 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand play Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 10ms Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 8ms Aug 31 15:36:56 volumiokwm volumio[1080]: info: sendMpdCommand play took 6 milliseconds Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 6ms Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 4ms Aug 31 15:36:56 volumiokwm volumio[1080]: info: Aug 31 15:36:56 volumiokwm volumio[1080]: ---------------------------- MPD announces system playlist update Aug 31 15:36:56 volumiokwm volumio[1080]: info: Ignoring MPD Status Update Aug 31 15:36:56 volumiokwm volumio[1080]: info: ------------------------------ 4ms Aug 31 15:36:57 volumiokwm volumio[1080]: info: Aug 31 15:36:57 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:57 volumiokwm volumio[1080]: info: Aug 31 15:36:57 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:57 volumiokwm volumio[1080]: info: Aug 31 15:36:57 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:57 volumiokwm volumio[1080]: info: Aug 31 15:36:57 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:57 volumiokwm volumio[1080]: info: Aug 31 15:36:57 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:57 volumiokwm volumio[1080]: info: Aug 31 15:36:57 volumiokwm volumio[1080]: ---------------------------- MPD announces state update: player Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand status took 15 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::getState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand status took 14 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand status took 12 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand status took 8 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand status took 6 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand status took 10 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseState Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":783,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite for lute in G minor, BWV 995 - 1/2","artist":"1600to1835","album":null,"uri":"https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService play Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":783,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite for lute in G minor, BWV 995 - 1/2","artist":"1600to1835","album":null,"uri":"https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService play Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:36:57 volumiokwm volumio[1080]: info: Received an update from plugin. extracting info from payload Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":783,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite for lute in G minor, BWV 995 - 1/2","artist":"1600to1835","album":null,"uri":"https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService play Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:36:57 volumiokwm volumio[1080]: info: Received an update from plugin. extracting info from payload Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":783,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite for lute in G minor, BWV 995 - 1/2","artist":"1600to1835","album":null,"uri":"https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService play Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:36:57 volumiokwm volumio[1080]: info: Received an update from plugin. extracting info from payload Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":783,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite for lute in G minor, BWV 995 - 1/2","artist":"1600to1835","album":null,"uri":"https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService play Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:36:57 volumiokwm volumio[1080]: info: Received an update from plugin. extracting info from payload Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: ------------------------------ 72ms Aug 31 15:36:57 volumiokwm volumio[1080]: info: ------------------------------ 128ms Aug 31 15:36:57 volumiokwm volumio[1080]: info: ------------------------------ 126ms Aug 31 15:36:57 volumiokwm volumio[1080]: info: ------------------------------ 122ms Aug 31 15:36:57 volumiokwm volumio[1080]: info: ------------------------------ 119ms Aug 31 15:36:57 volumiokwm volumio[1080]: info: sendMpdCommand playlistinfo took 101 milliseconds Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:36:57 volumiokwm volumio[1080]: info: ControllerMpd::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::servicePushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CorePlayQueue::getTrack 0 Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":783,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite for lute in G minor, BWV 995 - 1/2","artist":"1600to1835","album":null,"uri":"https://rr4---sn-oguelnze.googlevideo.com/videoplayback?expire=1756643816&ei=iO2zaKSHMeCcvcAPvsKjoAE&ip=221.255.129.17&id=o-ADsqWb7T53EkEo_-amK3m8X29SWSh58lSbdDkXsHeyZ9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1756622216%2C&mh=aX&mm=31%2C26&mn=sn-oguelnze%2Csn-3pm7dnl7&ms=au%2Conr&mv=m&mvi=4&pl=17&rms=au%2Cau&initcwndbps=3492500&bui=AY1jyLPXtPN_n3O9Tc7033uPgc6iwmE7LiEuBrV7i0Fy5AU6LwOiyRFhRAIwyfp8wuos06HznOeRE6kE&spc=l3OVKRFUJ3lAEIu3LoDnwycH4tlfxUhozmDTrRjQhgJKQI7utd62lNrrGBaFMHyjhA4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5J4dqiHXUphO86UiExskTJkQ&rqh=1&gir=yes&clen=14755727&dur=782.661&lmt=1744484078994898&mt=1756621763&fvip=1&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_REMIX&sefc=1&txp=8218224&n=X4tISrwaW3d-bA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJ0ymM0vvt7c_-w8usjLvm2NVDmkLS9ORrfe5kGrZDVAAiBxridJuUwevXxsQr7PPiJSDnWzAIKsObSRPzednB8fqw%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgN8wjlvQ_1lKwA8WAvdXd7JlsuCq1Hkuzi_e0inVOy5kCIQCaoIsrWANBB7y40R3iN7lqz2inCt26LyYKJy-p1Hox4w%3D%3D&pot=MnrnL8yivDzY4ecR4UucM65a8ttoE3WkAPPAyh9UZc90WN5Sc4EwWQkQZ5QIjqhdh3fggmWqtnlOIHq9Mh-7HmyexKLjqXEYDTFviNEPp1QrT5rZZRl66mN2NEqDts65Djn6N8SfIrWRObVHPejxtnIxYeKpuIB-Qmy3ZA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Aug 31 15:36:57 volumiokwm volumio[1080]: verbose: CURRENT POSITION 0 Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState stateService play Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::syncState currentStatus play Aug 31 15:36:57 volumiokwm volumio[1080]: info: Received an update from plugin. extracting info from payload Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreStateMachine::pushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: CoreCommandRouter::volumioPushState Aug 31 15:36:57 volumiokwm volumio[1080]: info: ------------------------------ 143ms Aug 31 15:37:10 volumiokwm ntpd[776]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 15:37:10 volumiokwm ntpd[776]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 31 15:37:10 volumiokwm ntpd[776]: DNS: Pool skipping: 46.232.60.193 Aug 31 15:37:10 volumiokwm ntpd[776]: DNS: Pool skipping: 162.159.200.1 Aug 31 15:37:10 volumiokwm ntpd[776]: DNS: Pool skipping: 162.159.200.123 Aug 31 15:37:10 volumiokwm ntpd[776]: DNS: Pool skipping: 167.179.119.205 Aug 31 15:37:10 volumiokwm ntpd[776]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 31 15:37:12 volumiokwm ntpd[776]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 15:37:12 volumiokwm ntpd[776]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 31 15:37:12 volumiokwm ntpd[776]: DNS: Pool skipping: 162.159.200.123 Aug 31 15:37:12 volumiokwm ntpd[776]: DNS: Pool skipping: 129.250.35.251 Aug 31 15:37:12 volumiokwm ntpd[776]: DNS: Pool taking: 45.77.20.103 Aug 31 15:37:12 volumiokwm ntpd[776]: DNS: Pool skipping: 142.91.105.55 Aug 31 15:37:12 volumiokwm ntpd[776]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 31 15:37:18 volumiokwm ntpd[776]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 15:37:18 volumiokwm ntpd[776]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 31 15:37:18 volumiokwm ntpd[776]: DNS: Pool taking: 47.79.37.210 Aug 31 15:37:18 volumiokwm ntpd[776]: DNS: Pool taking: 64.176.59.83 Aug 31 15:37:18 volumiokwm ntpd[776]: DNS: Pool skipping: 45.77.20.103 Aug 31 15:37:18 volumiokwm ntpd[776]: DNS: Pool taking: 108.160.132.224 Aug 31 15:37:18 volumiokwm ntpd[776]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 31 15:37:18 volumiokwm systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 31 15:37:18 volumiokwm dbus-daemon[573]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.32' (uid=0 pid=2923 comm="timedatectl show --property=NTPSynchronized --valu") Aug 31 15:37:18 volumiokwm systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 31 15:37:18 volumiokwm dbus-daemon[573]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 31 15:37:18 volumiokwm systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 31 15:37:18 volumiokwm setdatetime-helper.sh[2922]: Time is not synchronized. Attempting to sync... Aug 31 15:37:18 volumiokwm sudo[2931]: root : unable to resolve host volumiokwm: System error Aug 31 15:37:18 volumiokwm setdatetime-helper.sh[2931]: sudo: unable to resolve host volumiokwm: System error Aug 31 15:37:18 volumiokwm sudo[2931]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 31 Aug 2025 06:37:19 GMT#015' Aug 31 15:37:18 volumiokwm sudo[2931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 15:37:19 volumiokwm setdatetime-helper.sh[2932]: Sun Aug 31 15:37:19 JST 2025 Aug 31 15:37:19 volumiokwm sudo[2931]: pam_unix(sudo:session): session closed for user root Aug 31 15:37:19 volumiokwm setdatetime-helper.sh[2922]: Time synchronized successfully. Aug 31 15:37:19 volumiokwm systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 31 15:37:19 volumiokwm systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 31 15:37:30 volumiokwm volumio[1080]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 31 15:37:30 volumiokwm volumio[1080]: info: Preload queue cleared Aug 31 15:37:35 volumiokwm volumio[1080]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 31 15:37:35 volumiokwm volumio[1080]: Use XMLElement.setAttribute instead Aug 31 15:37:35 volumiokwm volumio[1080]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:37:35 volumiokwm volumio[1080]: TypeError: children[i].attr(...).value is not a function Aug 31 15:37:35 volumiokwm volumio[1080]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Aug 31 15:37:35 volumiokwm volumio[1080]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 31 15:37:35 volumiokwm volumio[1080]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:37:36 volumiokwm sudo[3010]: volumio : unable to resolve host volumiokwm: System error Aug 31 15:37:36 volumiokwm sudo[3010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-31 15:36' Aug 31 15:37:36 volumiokwm sudo[3010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"