May 29 17:57:00 volumio volumio[1251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 17:57:00 volumio volumio[1251]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 17:57:00 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:00 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:00 volumio volumio[1251]: info: Starting MyVolumio Remote Streaming Endpoints May 29 17:57:01 volumio volumio[1251]: info: MyVolumio login type: Token May 29 17:57:01 volumio volumio[1251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 17:57:01 volumio volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 17:57:04 volumio volumio[1251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 17:57:04 volumio volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 17:57:04 volumio volumio[1251]: info: Streaming services startup May 29 17:57:04 volumio volumio[1251]: info: Starting Streaming Daemon May 29 17:57:04 volumio volumio[1251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 17:57:05 volumio sudo[1713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 17:57:05 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 17:57:05 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 17:57:05 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root May 29 17:57:05 volumio dbus-daemon[597]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.30' (uid=0 pid=1720 comm="timedatectl show --property=NTPSynchronized --valu") May 29 17:57:05 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 17:57:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 29 17:57:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 29 17:57:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 29 17:57:05 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 29 17:57:05 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 29 17:57:05 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 17:57:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:57:05 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 29 17:57:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:57:05 volumio volumio[1251]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kTuLj0Nyhs4%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Fatboy%20Slim%20-%20Elevator%20Music%22%2C%22artist%22%3A%22Elevator%20Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FkTuLj0Nyhs4%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCOADEI4CSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBazYR8PE2JTQ6vupaG112usvRivA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kTuLj0Nyhs4%22%7D%7D%7D May 29 17:57:05 volumio volumio[1251]: info: [youtube2] InnertubeLoader: creating Innertube instance... May 29 17:57:05 volumio dbus-daemon[597]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 17:57:05 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 17:57:05 volumio setdatetime-helper.sh[1719]: Time is not synchronized. Attempting to sync... May 29 17:57:05 volumio sudo[1728]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 15:57:05 GMT#015' May 29 17:57:05 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 17:57:05 volumio setdatetime-helper.sh[1729]: Thu May 29 17:57:05 CEST 2025 May 29 17:57:05 volumio setdatetime-helper.sh[1719]: Time synchronized successfully. May 29 17:57:05 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root May 29 17:57:05 volumio systemd-journald[333]: Time jumped backwards, rotating. May 29 17:57:05 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 17:57:05 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 17:57:05 volumio volumio[1251]: error: Cannot start Volumio Streaming Daemon May 29 17:57:05 volumio volumio[1251]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 17:57:05 volumio volumio[1251]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 17:57:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 17:57:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 17:57:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 17:57:07 volumio volumio-remote-updater[629]: No test mode May 29 17:57:07 volumio volumio-remote-updater[629]: No alpha test mode May 29 17:57:07 volumio volumio[1251]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 17:57:08 volumio volumio[1251]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 17:57:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 17:57:09 volumio volumio[1251]: info: MyVolumio token set successfully May 29 17:57:09 volumio volumio[1251]: info: MYVOLUMIO: Adding device May 29 17:57:09 volumio volumio[1251]: info: MYVOLUMIO: Evaluating Server May 29 17:57:10 volumio volumio[1251]: info: MyVolumio status changed May 29 17:57:10 volumio volumio[1251]: info: Streaming services startup May 29 17:57:10 volumio volumio[1251]: info: Starting Streaming Daemon May 29 17:57:10 volumio volumio[1251]: info: Removing browser output: myVolumio user plan is not superstar May 29 17:57:10 volumio volumio[1251]: info: Removing audio output: May 29 17:57:10 volumio volumio[1251]: info: Stoppping Tunnel 1 May 29 17:57:10 volumio sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 17:57:10 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 17:57:10 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root May 29 17:57:10 volumio sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 17:57:10 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 17:57:10 volumio volumio[1251]: info: Setting Geolocation for MyVolumio to eu2 May 29 17:57:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:10 volumio volumio[1251]: error: Cannot start Volumio Streaming Daemon May 29 17:57:10 volumio volumio[1251]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 17:57:10 volumio volumio[1251]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio 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. May 29 17:57:11 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root May 29 17:57:11 volumio volumio[1251]: info: Remote SSH Stopped May 29 17:57:11 volumio volumio[1251]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 29 17:57:11 volumio volumio[1251]: info: Updating MyVolumio device info May 29 17:57:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:17 volumio upmpdcli[1773]: writing RSA key May 29 17:57:34 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 17:57:38 volumio volumio[1251]: info: MYVOLUMIO: Adding device May 29 17:57:38 volumio volumio[1251]: info: MYVOLUMIO: Evaluating Server May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::volumioGetBrowseSources May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 17:57:38 volumio volumio[1251]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 29 17:57:38 volumio volumio[1251]: info: Completed starting MyVolumio Plugin May 29 17:57:38 volumio volumio[1251]: [Metrics] CommandRouter: 83s 440.53ms May 29 17:57:38 volumio volumio[1251]: info: CoreCommandRouter::volumiosetStartupVolume May 29 17:57:39 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 17:57:39 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:57:39 volumio volumio[1251]: info: CoreCommandRouter::Close All Modals sent May 29 17:57:39 volumio volumio[1251]: info: CoreCommandRouter::Close All Modals sent May 29 17:57:39 volumio volumio[1251]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 29 17:57:39 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay May 29 17:57:39 volumio volumio[1251]: info: CoreStateMachine::play index undefined May 29 17:57:39 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 17:57:39 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:57:39 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer May 29 17:57:39 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:57:39 volumio volumio[1251]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kTuLj0Nyhs4%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Fatboy%20Slim%20-%20Elevator%20Music%22%2C%22artist%22%3A%22Elevator%20Music%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FkTuLj0Nyhs4%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCOADEI4CSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBazYR8PE2JTQ6vupaG112usvRivA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22kTuLj0Nyhs4%22%7D%7D%7D May 29 17:57:39 volumio volumio[1251]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 29 17:57:39 volumio volumio[1251]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for IQaudIODAC May 29 17:57:39 volumio volumio[1251]: aplay: main:831: audio open error: No such device May 29 17:57:39 volumio volumio[1251]: info: [youtube2] InnertubeLoader: obtaining po_token by datasyncIdToken... May 29 17:57:40 volumio volumio[1251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 124 May 29 17:57:40 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 29 17:57:40 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 17:57:40 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 29 17:57:40 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 29 17:57:40 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:57:43 volumio volumio[1251]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) May 29 17:57:43 volumio volumio[1251]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token May 29 17:57:43 volumio volumio[1251]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... May 29 17:58:07 volumio volumio[1251]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds May 29 17:58:07 volumio volumio[1251]: info: BOOT COMPLETED May 29 17:58:07 volumio volumio[1251]: info: Setting Geolocation for MyVolumio to eu2 May 29 17:58:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:58:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:58:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:58:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 17:58:07 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState May 29 17:58:07 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:07 volumio volumio[1251]: info: Preload queue cleared May 29 17:58:09 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:09 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 29 17:58:09 volumio volumio[1251]: info: Updating MyVolumio device info May 29 17:58:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:58:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:58:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 17:58:09 volumio volumio[1251]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 29 17:58:09 volumio volumio[1251]: info: sendMpdCommand stop took 95 milliseconds May 29 17:58:09 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: sendMpdCommand clear took 25 milliseconds May 29 17:58:09 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aNmZDvnk6dsP3MTpOA&ip=86.95.56.171&id=o-ACLsk_WyVni74PmTCBkCrY3MSZRfS2KtNMZDPsKTj_ty&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=u&mvi=4&pl=27&rms=au%2Cau&siu=1&bui=AY1jyLMvHbPgp58Hco2qTAS558W31D6NlWS5FdC0-cwTTCqE5OoZp1xRK-EE3zB0G8g1uYqG4g&spc=l3OVKc6CEnQTB5fUUPDYwBmcAk4PExDOO-8o0KEhj8V6pSrTgCtAw1XToMBF5eA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=e-jyXgnhSOAmAM-TlZ5TwOgQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533365&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=i5zShLO_HP2CIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms&lsig=APaTxxMwRQIgI-tius_uk7YPso9CA78V6r7lqqR6VffsH6qTW-lSGTICIQC1fnJkHm4mUkUEj39PJCYv1yNCqBYqhxbdGx1fVTpHmQ%3D%3D&sig=AJfQdSswRgIhAJZhwBZJi7GfQTNdL4VC94NiR75ZaOnfnsRi5tf2LOXpAiEAlkxRP2auerA24YNHFKQ7c0xVIgYs2tGpdQ-Ff4y4MLw%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube" May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: error: updateQueue error: null May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 58ms May 29 17:58:09 volumio volumio[1251]: info: sendMpdCommand addid "https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aNmZDvnk6dsP3MTpOA&ip=86.95.56.171&id=o-ACLsk_WyVni74PmTCBkCrY3MSZRfS2KtNMZDPsKTj_ty&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=u&mvi=4&pl=27&rms=au%2Cau&siu=1&bui=AY1jyLMvHbPgp58Hco2qTAS558W31D6NlWS5FdC0-cwTTCqE5OoZp1xRK-EE3zB0G8g1uYqG4g&spc=l3OVKc6CEnQTB5fUUPDYwBmcAk4PExDOO-8o0KEhj8V6pSrTgCtAw1XToMBF5eA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=e-jyXgnhSOAmAM-TlZ5TwOgQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533365&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=i5zShLO_HP2CIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms&lsig=APaTxxMwRQIgI-tius_uk7YPso9CA78V6r7lqqR6VffsH6qTW-lSGTICIQC1fnJkHm4mUkUEj39PJCYv1yNCqBYqhxbdGx1fVTpHmQ%3D%3D&sig=AJfQdSswRgIhAJZhwBZJi7GfQTNdL4VC94NiR75ZaOnfnsRi5tf2LOXpAiEAlkxRP2auerA24YNHFKQ7c0xVIgYs2tGpdQ-Ff4y4MLw%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube" took 60 milliseconds May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 54ms May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 53ms May 29 17:58:09 volumio volumio[1251]: verbose: MPD COMMAND [object Object] May 29 17:58:09 volumio volumio[1251]: verbose: MPD COMMAND [object Object] May 29 17:58:09 volumio volumio[1251]: verbose: MPD COMMAND [object Object] May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 54ms May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 19ms May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 17ms May 29 17:58:09 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 17:58:09 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: May 29 17:58:09 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:09 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 20ms May 29 17:58:09 volumio volumio[1251]: info: sendMpdCommand play took 14 milliseconds May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 21ms May 29 17:58:09 volumio volumio[1251]: info: ------------------------------ 14ms May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop May 29 17:58:11 volumio volumio[1251]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand stop took 72 milliseconds May 29 17:58:11 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:11 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:11 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand status took 52 milliseconds May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand clear took 48 milliseconds May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand status took 39 milliseconds May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand status took 32 milliseconds May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube" May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:11 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:11 volumio volumio[1251]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 17:58:11 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:11 volumio volumio[1251]: info: No code May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:11 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:11 volumio volumio[1251]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 17:58:11 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::syncState stateService stop May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:11 volumio volumio[1251]: info: No code May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:11 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 307ms May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 296ms May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: Preload queue cleared May 29 17:58:11 volumio volumio[1251]: error: updateQueue error: null May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 319ms May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 279 milliseconds May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand addid "https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube" took 279 milliseconds May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 47ms May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 45ms May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 29 17:58:11 volumio volumio[1251]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 29 17:58:11 volumio volumio[1251]: verbose: MPD COMMAND [object Object] May 29 17:58:11 volumio volumio[1251]: verbose: MPD COMMAND [object Object] May 29 17:58:11 volumio volumio[1251]: verbose: MPD COMMAND [object Object] May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 404ms May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 204ms May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 152ms May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 146ms May 29 17:58:11 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 17:58:11 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: May 29 17:58:11 volumio volumio[1251]: ---------------------------- MPD announces system playlist update May 29 17:58:11 volumio volumio[1251]: info: Ignoring MPD Status Update May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 38ms May 29 17:58:11 volumio volumio[1251]: info: sendMpdCommand play took 17 milliseconds May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 18ms May 29 17:58:11 volumio volumio[1251]: info: ------------------------------ 8ms May 29 17:58:13 volumio volumio[1251]: info: May 29 17:58:13 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:13 volumio volumio[1251]: info: May 29 17:58:13 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:13 volumio volumio[1251]: info: May 29 17:58:13 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:13 volumio volumio[1251]: info: May 29 17:58:13 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand status took 38 milliseconds May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 17:58:13 volumio volumio[1251]: info: May 29 17:58:13 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:13 volumio volumio[1251]: info: May 29 17:58:13 volumio volumio[1251]: ---------------------------- MPD announces state update: player May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::getState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand status took 90 milliseconds May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clearerror May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand status took 90 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand clearerror took 57 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand status took 71 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 69 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand status took 67 milliseconds May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:13 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:13 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3685,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fatboy Slim - Elevator Music","artist":"Elevator Music","album":"YouTube","uri":"https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 17:58:13 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:13 volumio volumio[1251]: info: ------------------------------ 217ms May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand status took 195 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand clearerror took 172 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand clearerror took 170 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 134 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 134 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 150 milliseconds May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 149 milliseconds May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseState May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:13 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:13 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3685,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fatboy Slim - Elevator Music","artist":"Elevator Music","album":"YouTube","uri":"https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 17:58:13 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:13 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:13 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3685,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fatboy Slim - Elevator Music","artist":"Elevator Music","album":"YouTube","uri":"https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 17:58:13 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:13 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:13 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3685,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fatboy Slim - Elevator Music","artist":"Elevator Music","album":"YouTube","uri":"https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 17:58:13 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:13 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:13 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3685,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fatboy Slim - Elevator Music","artist":"Elevator Music","album":"YouTube","uri":"https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 17:58:13 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:13 volumio volumio[1251]: info: ------------------------------ 503ms May 29 17:58:13 volumio volumio[1251]: info: ------------------------------ 502ms May 29 17:58:13 volumio volumio[1251]: info: ------------------------------ 501ms May 29 17:58:13 volumio volumio[1251]: info: ------------------------------ 464ms May 29 17:58:13 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 242 milliseconds May 29 17:58:13 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo May 29 17:58:13 volumio volumio[1251]: info: ControllerMpd::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::servicePushState May 29 17:58:13 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 May 29 17:58:13 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3685,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fatboy Slim - Elevator Music","artist":"Elevator Music","album":"YouTube","uri":"https://rr4---sn-32o-1the.googlevideo.com/videoplayback?expire=1748555888&ei=EIQ4aKmtKvPD6dsPweu6-QU&ip=86.95.56.171&id=o-AFVxvdDGx-Vom9jNMJLF3hKkyBAAXugExCHorBK_1BQk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748534288%2C&mh=__&mm=31%2C29&mn=sn-32o-1the%2Csn-5hne6nzk&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=27&rms=au%2Cau&initcwndbps=4111250&siu=1&bui=AY1jyLNlS9wa6YmKOaTy2PCJihMOo4RUXX2FE65swBHS5zA1grclmPJRp1fNUyf37xh983qO3Q&spc=l3OVKVZisx89O2JG5l3Akd-ZkDA80aOiVxIb-VEcIYqEX-jXDBNEzeDmskIoH7g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=L0C0qh5iAmhvlX1g-4OR4TEQ&rqh=1&gir=yes&clen=60707961&dur=3684.901&lmt=1733170410973414&mt=1748533987&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=t4Nvo6pTLsM9sA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAJUrNzmjY92W-lslS6zM2W0yy3mrQN7BVTrZT7GwF2f-AiAPCBA8TUucBuZ5QDuOqiSDItWQhQAtWJLGhCVLFVnqAg%3D%3D&sig=AJfQdSswRgIhAJf4FZl7GussLOjvsxRXSUkchyhKGZlVwW7iUbmAOhgUAiEAz5joBpOKBgkhV2YCXFWJKRyGqQwRzxzWUUHzznDkyKk%3D&pot=Ml80gqYiUs5ZuzS-gKfVnepCMnHe4BqTH_9SFVcFhKBHN2km6NeFm1wqGczKdjs6-VCtl87hUIEAKGEZfT2d6EIZhNOWrN5RlfJcIJsxbMsF0F47gPio5RHk1McXJAAeag%3D%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 17:58:13 volumio volumio[1251]: verbose: CURRENT POSITION 0 May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop May 29 17:58:13 volumio volumio[1251]: info: CoreStateMachine::pushState May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 17:58:13 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState May 29 17:58:13 volumio volumio[1251]: info: ------------------------------ 526ms May 29 17:58:23 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 17:58:24 volumio volumio[1251]: info: Preload queue cleared May 29 17:58:27 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 17:58:27 volumio dbus-daemon[597]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.32' (uid=0 pid=1913 comm="timedatectl show --property=NTPSynchronized --valu") May 29 17:58:27 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 17:58:27 volumio dbus-daemon[597]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 17:58:27 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 17:58:27 volumio setdatetime-helper.sh[1912]: Time is not synchronized. Attempting to sync... May 29 17:58:28 volumio sudo[1921]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 15:58:28 GMT#015' May 29 17:58:28 volumio sudo[1921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 17:58:28 volumio setdatetime-helper.sh[1922]: Thu May 29 17:58:28 CEST 2025 May 29 17:58:28 volumio systemd-journald[333]: Time jumped backwards, rotating. May 29 17:58:28 volumio sudo[1921]: pam_unix(sudo:session): session closed for user root May 29 17:58:28 volumio setdatetime-helper.sh[1912]: Time synchronized successfully. May 29 17:58:28 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 17:58:28 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 17:58:31 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 17:58:31 volumio volumio[1251]: Use XMLElement.setAttribute instead May 29 17:58:31 volumio volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 17:58:31 volumio volumio[1251]: TypeError: children[i].attr(...).value is not a function May 29 17:58:31 volumio volumio[1251]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 May 29 17:58:31 volumio volumio[1251]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 29 17:58:31 volumio volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 17:58:35 volumio sudo[1953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 17:57' May 29 17:58:35 volumio sudo[1953]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"