Sep 18 08:02:00 volumio dhcpcd[748]: wlan0: using IPv4LL address 169.254.5.184 Sep 18 08:02:00 volumio avahi-daemon[713]: Registering new address record for 169.254.5.184 on wlan0.IPv4. Sep 18 08:02:00 volumio dhcpcd[748]: wlan0: adding route to 169.254.0.0/16 Sep 18 08:02:00 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Sep 18 08:02:00 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Sep 18 08:02:00 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 18 08:02:00 volumio systemd[1]: welcome.service: Deactivated successfully. Sep 18 08:02:00 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 18 08:02:00 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 18 08:02:00 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 18 08:02:00 volumio welcome[1248]: Resolved ip:[3] 192.168.0.241 192.168.211.1 169.254.5.184 Sep 18 08:02:00 volumio setdatetime-helper.sh[1238]: Time is not synchronized. Attempting to sync... Sep 18 08:02:00 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 18 08:02:00 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Sep 18 08:02:00 volumio sudo[1258]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 18 Sep 2025 06:02:00 GMT#015' Sep 18 08:02:00 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 18 08:02:00 volumio setdatetime-helper.sh[1259]: Thu Sep 18 08:02:00 CEST 2025 Sep 18 08:02:00 volumio systemd-journald[338]: Time jumped backwards, rotating. Sep 18 08:02:00 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:00 volumio setdatetime-helper.sh[1238]: Time synchronized successfully. Sep 18 08:02:00 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Sep 18 08:02:00 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 18 08:02:00 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 18 08:02:00 volumio volumio[1047]: info: Loading plugin "ytmusic"... Sep 18 08:02:00 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Sep 18 08:02:00 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Sep 18 08:02:00 volumio ntpd[902]: IO: Listen normally on 5 wlan0 169.254.5.184:123 Sep 18 08:02:00 volumio ntpd[902]: IO: new interface(s) found: waking up resolver Sep 18 08:02:02 volumio volumio[1047]: info: Loading plugin "autostart"... Sep 18 08:02:02 volumio volumio[1047]: info: Applying required configuration parameters for plugin autostart Sep 18 08:02:02 volumio volumio[1047]: info: AutoStart - onVolumioStart - read config.json Sep 18 08:02:02 volumio volumio[1047]: info: Loading plugin "outputs"... Sep 18 08:02:02 volumio volumio[1047]: info: Loading plugin "albumart"... Sep 18 08:02:02 volumio volumio[1047]: info: Plugin example_plugin is not enabled Sep 18 08:02:02 volumio volumio[1047]: info: Loading plugin "inputs"... Sep 18 08:02:02 volumio volumio[1047]: info: Loading plugin "updater_comm"... Sep 18 08:02:02 volumio volumio[1047]: info: Plugin mpdemulation is not enabled Sep 18 08:02:02 volumio volumio[1047]: info: Loading plugin "rest_api"... Sep 18 08:02:02 volumio volumio[1047]: info: Loading plugin "websocket"... Sep 18 08:02:02 volumio volumio[1047]: info: Starting Socket.io Server version 1.7.4 Sep 18 08:02:02 volumio volumio[1047]: info: Loading i18n strings for locale en Sep 18 08:02:02 volumio volumio[1047]: Updating browse sources language Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::initPlayerControls Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: Express server listening on port 3000 Sep 18 08:02:02 volumio volumio[1047]: [Metrics] WebUI: 11s 498.19ms Sep 18 08:02:02 volumio volumio[1047]: info: CoreStateMachine::resetVolumioState Sep 18 08:02:02 volumio volumio[1047]: info: CoreStateMachine::getcurrentVolume Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::volumioRetrievevolume Sep 18 08:02:02 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:02 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:02 volumio volumio[1047]: info: Volumio Network Manager: Network status updated: 1 Sep 18 08:02:03 volumio volumio[1047]: info: Reloading queue from file Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 1 Sep 18 08:02:03 volumio volumio[1047]: info: CoreStateMachine::setRepeat true single undefined Sep 18 08:02:03 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:03 volumio volumio[1047]: info: CoreStateMachine::setRandom true Sep 18 08:02:03 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 2 Sep 18 08:02:03 volumio volumio[1047]: info: Setting Device type: Raspberry PI Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 3 Sep 18 08:02:03 volumio volumio[1047]: info: Completed loading Core Plugins Sep 18 08:02:03 volumio volumio[1047]: info: Preparing to generate the ALSA configuration file Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 18 08:02:03 volumio volumio[1047]: info: Discovery: adding e2ab690b-e09b-4683-abfc-3f004c238dee Sep 18 08:02:03 volumio volumio[1047]: info: Discovery: Found device Volumio Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 5 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 7 Sep 18 08:02:03 volumio volumio[1047]: info: Discovery: this is already registered, e2ab690b-e09b-4683-abfc-3f004c238dee Sep 18 08:02:03 volumio volumio[1047]: info: Discovery: Found device Volumio Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 8 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 9 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 10 Sep 18 08:02:03 volumio volumio[1047]: info: Discovery: this is already registered, e2ab690b-e09b-4683-abfc-3f004c238dee Sep 18 08:02:03 volumio volumio[1047]: info: Discovery: Found device Volumio Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 11 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 12 Sep 18 08:02:03 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 13 Sep 18 08:02:03 volumio volumio[1047]: info: Asound.conf file unchanged, so no further update is needed Sep 18 08:02:03 volumio volumio[1047]: info: Output device has changed, restarting MPD Sep 18 08:02:03 volumio volumio[1047]: info: Output device has changed, restarting Shairport Sync Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:03 volumio sudo[1330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 08:02:03 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:03 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:03 volumio sudo[1334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 08:02:03 volumio sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:03 volumio volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 08:02:03 volumio volumio[1047]: info: ___________ START PLUGINS ___________ Sep 18 08:02:03 volumio volumio[1047]: info: ControllerMpd::onStart: Initializing MPD Sep 18 08:02:03 volumio volumio[1047]: info: Creating MPD Configuration file Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 08:02:03 volumio volumio[1047]: info: [1758175323284] CoreMusicLibrary::Adding element Media Servers Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:03 volumio sudo[1343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 08:02:03 volumio sudo[1340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 08:02:03 volumio sudo[1343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:03 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 18 08:02:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Sep 18 08:02:03 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:03 volumio volumio[1307]: Forking 3 albumart workers Sep 18 08:02:03 volumio systemd[1]: mpd.service: Deactivated successfully. Sep 18 08:02:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 18 08:02:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Sep 18 08:02:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 18 08:02:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 18 08:02:03 volumio volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 18 08:02:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:03 volumio volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 08:02:03 volumio volumio[1047]: info: [1758175323405] CoreMusicLibrary::Adding element Last_100 Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 08:02:03 volumio volumio[1047]: info: [1758175323407] CoreMusicLibrary::Adding element Webradio Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 08:02:03 volumio volumio[1047]: info: Initializing BBC Radios Sep 18 08:02:03 volumio sudo[1352]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 08:02:03 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 18 08:02:03 volumio sudo[1385]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 18 08:02:03 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 08:02:03 volumio volumio[1047]: info: [1758175323602] CoreMusicLibrary::Adding element YouTube2 Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 08:02:03 volumio volumio[1047]: Cannot find translation for source YouTube2 Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 08:02:03 volumio volumio[1047]: info: [1758175323878] CoreMusicLibrary::Adding element YouTube Music Sep 18 08:02:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 08:02:03 volumio volumio[1047]: Cannot find translation for source YouTube2 Sep 18 08:02:03 volumio volumio[1047]: Cannot find translation for source YouTube Music Sep 18 08:02:03 volumio volumio[1047]: info: AutoStart - onStart Sep 18 08:02:03 volumio volumio[1047]: info: Volumio Calling Home Sep 18 08:02:04 volumio volumio[1353]: Starting albumart workers Sep 18 08:02:04 volumio volumio[1358]: Starting albumart workers Sep 18 08:02:05 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 14 Sep 18 08:02:05 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 15 Sep 18 08:02:05 volumio sudo[1434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 18 08:02:05 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:05 volumio sudo[1432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 18 08:02:05 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:06 volumio sudo[1432]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:06 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:06 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.0.241 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 16 Sep 18 08:02:06 volumio volumio[1047]: info: MPD Permissions set Sep 18 08:02:06 volumio volumio[1047]: info: MPD Permissions set Sep 18 08:02:06 volumio volumio[1047]: info: Volumio called home Sep 18 08:02:06 volumio volumio[1359]: Starting albumart workers Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:06 volumio volumio[1047]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:07 volumio volumio[1047]: info: Received Get System Info Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 08:02:07 volumio volumio[1047]: info: Discovery: Getting this device information Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:07 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:07 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetVisibleSources Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:07 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:07 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 18 08:02:07 volumio volumio[1047]: info: Received Get System Info Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 18 08:02:07 volumio volumio[1047]: info: Discovery: Getting this device information Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:07 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:07 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:07 volumio volumio[1047]: info: Listing playlists Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Sep 18 08:02:07 volumio volumio[1047]: info: CoreStateMachine::getQueue Sep 18 08:02:07 volumio volumio[1047]: info: CorePlayQueue::getQueue Sep 18 08:02:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 18 08:02:07 volumio mpd[1391]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 18 08:02:07 volumio mpd[1391]: output: No 'audio_output' defined in config file Sep 18 08:02:07 volumio mpd[1391]: output: Successfully detected a sndio audio device Sep 18 08:02:07 volumio mpd[1391]: zeroconf: No global port, disabling zeroconf Sep 18 08:02:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Sep 18 08:02:07 volumio sudo[1343]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:07 volumio sudo[1334]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:07 volumio volumio[1047]: info: Starting Shairport Sync Sep 18 08:02:07 volumio volumio[1047]: info: Starting Shairport Sync Sep 18 08:02:07 volumio sudo[1458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 18 08:02:07 volumio sudo[1458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:07 volumio sudo[1460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 08:02:07 volumio volumio[1047]: info: Starting Shairport Sync Sep 18 08:02:07 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:07 volumio sudo[1463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 08:02:08 volumio sudo[1463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:08 volumio sudo[1465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 08:02:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Sep 18 08:02:08 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:08 volumio sudo[1458]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 18 08:02:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Sep 18 08:02:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 18 08:02:08 volumio systemd[1]: shairport-sync.service: Consumed 1.613s CPU time. Sep 18 08:02:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 18 08:02:08 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 18 08:02:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Sep 18 08:02:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 18 08:02:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 18 08:02:08 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:08 volumio sudo[1463]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:08 volumio volumio[1047]: error: MPD error: The expression evaluated to a falsy value: Sep 18 08:02:08 volumio volumio[1047]: assert.ok(self.idling) Sep 18 08:02:08 volumio volumio[1047]: error: The expression evaluated to a falsy value: Sep 18 08:02:08 volumio volumio[1047]: assert.ok(self.idling) Sep 18 08:02:08 volumio volumio[1047]: info: MPD running with PID1391 Sep 18 08:02:08 volumio volumio[1047]: ,establishing connection Sep 18 08:02:08 volumio volumio[1047]: error: updateQueue error: null Sep 18 08:02:08 volumio volumio[1047]: info: Shairport-Sync Started Sep 18 08:02:08 volumio volumio[1047]: Error adding Membership: Error: addMembership EINVAL Sep 18 08:02:08 volumio volumio[1047]: info: Shairport-Sync Started Sep 18 08:02:08 volumio volumio[1047]: info: Shairport-Sync Started Sep 18 08:02:08 volumio volumio[1047]: info: Upmpdcli Daemon Started Sep 18 08:02:08 volumio volumio[1047]: error: updateQueue error: null Sep 18 08:02:08 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:08 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:09 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 18 08:02:09 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 18 08:02:09 volumio volumio[1047]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 18 08:02:09 volumio volumio[1047]: info: CoreCommandRouter::volumioRetrievevolume Sep 18 08:02:09 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:09 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:09 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:09 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:09 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:09 volumio volumio[1047]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Sep 18 08:02:09 volumio volumio[1047]: (TypeError) Cannot read properties of undefined (reading 'vol') Sep 18 08:02:09 volumio volumio[1047]: TypeError: Cannot read properties of undefined (reading 'vol') Sep 18 08:02:09 volumio volumio[1047]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Sep 18 08:02:09 volumio volumio[1047]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 18 08:02:09 volumio volumio[1047]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Sep 18 08:02:09 volumio volumio[1047]: at async /data/plugins/music_service/ytcr/dist/index.js:330:13 Sep 18 08:02:09 volumio volumio-remote-updater[724]: [2025-09-18 08:02:09] [connect] Successful connection Sep 18 08:02:09 volumio volumio[1047]: info: Completed starting Core Plugins Sep 18 08:02:09 volumio volumio[1047]: info: ------------------------------------------- Sep 18 08:02:09 volumio volumio[1047]: info: ----- MyVolumio plugins startup ---- Sep 18 08:02:09 volumio volumio[1047]: info: ------------------------------------------- Sep 18 08:02:09 volumio volumio[1047]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 18 08:02:09 volumio volumio-remote-updater[724]: [2025-09-18 08:02:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1758175329 101 Sep 18 08:02:09 volumio volumio[1047]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 18 Sep 18 08:02:10 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Sep 18 08:02:10 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 18 08:02:11 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 18 08:02:12 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Sep 18 08:02:13 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 18 08:02:13 volumio mpd[1391]: exception: No database Sep 18 08:02:13 volumio mpd[1391]: exception: No database Sep 18 08:02:13 volumio volumio[1047]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Sep 18 08:02:13 volumio mpd[1391]: exception: No database Sep 18 08:02:16 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 18 08:02:18 volumio volumio[1047]: info: Adding plugin bluetooth to MyMusic Plugins Sep 18 08:02:18 volumio volumio[1047]: info: Adding plugin multiroom to MyMusic Plugins Sep 18 08:02:18 volumio volumio[1047]: info: Adding plugin metavolumio to MyMusic Plugins Sep 18 08:02:18 volumio volumio[1047]: info: Adding plugin cd_controller to MyMusic Plugins Sep 18 08:02:18 volumio volumio[1047]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 18 08:02:18 volumio volumio[1047]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 18 08:02:18 volumio volumio[1047]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 18 08:02:20 volumio volumio[1047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 18 08:02:20 volumio volumio[1047]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 18 08:02:20 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:20 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:20 volumio volumio[1047]: info: Starting MyVolumio Remote Streaming Endpoints Sep 18 08:02:20 volumio volumio[1047]: info: MyVolumio login type: Token Sep 18 08:02:20 volumio volumio[1047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 18 08:02:20 volumio volumio[1047]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 18 08:02:21 volumio volumio[1047]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 18 08:02:21 volumio volumio[1047]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 18 08:02:21 volumio volumio[1047]: info: Streaming services startup Sep 18 08:02:21 volumio volumio[1047]: info: Starting Streaming Daemon Sep 18 08:02:21 volumio sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 18 08:02:21 volumio sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:21 volumio sudo[1526]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:21 volumio volumio[1047]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 18 08:02:21 volumio volumio[1047]: error: Cannot start Volumio Streaming Daemon Sep 18 08:02:21 volumio volumio[1047]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 18 08:02:21 volumio volumio[1047]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 18 08:02:22 volumio volumio[1047]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 18 08:02:22 volumio volumio[1047]: info: MyVolumio token set successfully Sep 18 08:02:22 volumio volumio[1047]: info: MYVOLUMIO: Adding device Sep 18 08:02:22 volumio volumio[1047]: info: MYVOLUMIO: Evaluating Server Sep 18 08:02:23 volumio volumio[1047]: info: MyVolumio status changed Sep 18 08:02:23 volumio volumio[1047]: info: Streaming services startup Sep 18 08:02:23 volumio volumio[1047]: info: Starting Streaming Daemon Sep 18 08:02:23 volumio volumio[1047]: info: Removing browser output: myVolumio user plan is not superstar Sep 18 08:02:23 volumio volumio[1047]: info: Removing audio output: Sep 18 08:02:23 volumio volumio[1047]: info: Stoppping Tunnel 1 Sep 18 08:02:23 volumio sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 18 08:02:23 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:23 volumio sudo[1550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 18 08:02:23 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 18 08:02:23 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. Sep 18 08:02:23 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. Sep 18 08:02:23 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. Sep 18 08:02:23 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. Sep 18 08:02:23 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. Sep 18 08:02:23 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. Sep 18 08:02:23 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. Sep 18 08:02:23 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. Sep 18 08:02:23 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:23 volumio volumio[1047]: info: Remote SSH Stopped Sep 18 08:02:23 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Sep 18 08:02:23 volumio volumio[1047]: error: Cannot start Volumio Streaming Daemon Sep 18 08:02:23 volumio volumio[1047]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 18 08:02:23 volumio volumio[1047]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 18 08:02:23 volumio volumio[1047]: info: Setting Geolocation for MyVolumio to eu4 Sep 18 08:02:23 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:23 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:23 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:23 volumio volumio[1047]: info: AutoStart - Plugin is starting Sep 18 08:02:23 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::getQueue Sep 18 08:02:23 volumio volumio[1047]: info: CorePlayQueue::getQueue Sep 18 08:02:23 volumio volumio[1047]: info: AutoStart - start playing Sep 18 08:02:23 volumio volumio[1047]: info: AutoStart - start playing with no specific position Sep 18 08:02:23 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::play index 0 Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::stop Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::play index undefined Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:02:23 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:23 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Sep 18 08:02:23 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:23 volumio volumio[1047]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Wvh4D0-bR0U%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22DEEP%20BASS%20%26%20BEST%20VOICE%20-%20HI%20RES%20MUSIC%2024%20BIT%20-%20NATURAL%20BEAT%20RECORDS%22%2C%22artist%22%3A%22Natural%20Beat%20Records%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FWvh4D0-bR0U%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLA6BTH3zh3n5ogfkhMEhq9RXGnpJQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Wvh4D0-bR0U%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D%7D Sep 18 08:02:23 volumio volumio[1047]: info: [youtube2] InnertubeLoader: creating Innertube instance... Sep 18 08:02:24 volumio volumio[1047]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 18 08:02:24 volumio volumio[1047]: info: Updating MyVolumio device info Sep 18 08:02:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:24 volumio volumio[1047]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 18 08:02:26 volumio upmpdcli[1569]: writing RSA key Sep 18 08:02:29 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 18 08:02:33 volumio volumio[1047]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... Sep 18 08:02:34 volumio volumio[1047]: info: MYVOLUMIO: Adding device Sep 18 08:02:34 volumio volumio[1047]: info: MYVOLUMIO: Evaluating Server Sep 18 08:02:34 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 08:02:34 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:34 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 18 08:02:34 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Sep 18 08:02:34 volumio volumio[1047]: info: CoreStateMachine::play index undefined Sep 18 08:02:34 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:02:34 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:34 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Sep 18 08:02:34 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:34 volumio volumio[1047]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Wvh4D0-bR0U%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22DEEP%20BASS%20%26%20BEST%20VOICE%20-%20HI%20RES%20MUSIC%2024%20BIT%20-%20NATURAL%20BEAT%20RECORDS%22%2C%22artist%22%3A%22Natural%20Beat%20Records%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FWvh4D0-bR0U%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLA6BTH3zh3n5ogfkhMEhq9RXGnpJQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Wvh4D0-bR0U%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A0%7D%7D%7D Sep 18 08:02:34 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 18 08:02:34 volumio volumio-remote-updater[724]: Test mode disabled Sep 18 08:02:34 volumio volumio-remote-updater[724]: Alpha mode disabled Sep 18 08:02:34 volumio volumio-remote-updater[724]: Alpha legacy test mode disabled Sep 18 08:02:34 volumio volumio[1047]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 18 08:02:34 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 18 08:02:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 18 08:02:36 volumio volumio[1047]: info: Setting Geolocation for MyVolumio to eu4 Sep 18 08:02:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:36 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:36 volumio volumio[1047]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Sep 18 08:02:36 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:36 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:36 volumio volumio[1047]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) Sep 18 08:02:36 volumio volumio[1047]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token Sep 18 08:02:36 volumio volumio[1047]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... Sep 18 08:02:36 volumio volumio[1047]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 18 08:02:43 volumio volumio[1047]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds Sep 18 08:02:43 volumio volumio[1047]: info: Updating MyVolumio device info Sep 18 08:02:43 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:43 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:43 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetBrowseSources Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 08:02:44 volumio volumio[1047]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 18 08:02:44 volumio volumio[1047]: info: Completed starting MyVolumio Plugin Sep 18 08:02:44 volumio volumio[1047]: [Metrics] CommandRouter: 52s 178.56ms Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::volumiosetStartupVolume Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::Close All Modals sent Sep 18 08:02:44 volumio volumio[1047]: info: CoreCommandRouter::Close All Modals sent Sep 18 08:02:44 volumio volumio[1047]: warn: [youtube2] Error getting stream with default client in VideoModel.getInfo(Wvh4D0-bR0U): No valid URL to decipher - retry with 'WEB_EMBEDDED' client. Sep 18 08:02:44 volumio volumio[1047]: warn: [youtube2] Error getting stream with default client in VideoModel.getInfo(Wvh4D0-bR0U): No valid URL to decipher - retry with 'WEB_EMBEDDED' client. Sep 18 08:02:44 volumio volumio[1047]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 18 08:02:45 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand stop took 95 milliseconds Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand clear took 2 milliseconds Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaNaPLOL26dsPqriZqA0&ip=94.172.95.126&id=o-AP9aVP4Wo7NnXKY5m2i09Cl27k2l78mXKsJX5cZ9We4n&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lne6&ms=au%2Crdu&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSWDjjIqn0euc1_-iz4Y46y7I9cAGu_iB2jF7aUTgSo3AyxP5Aj15HCAdqYYpz5X_IYdgqrObB8h&spc=hcYD5XmyQjzd_JMtKns8JG4UQhvG8g3muGgBcHLhxE9_dZuiF56Iqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1gaRyJAluahHqtMktUED_7UQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=Yd6TxSKQ-YMVeQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAPbMhMLp6y2P3I07RMSizY5ADXzWzq59nDTThw0AzaHWAiAYBt2702jkVfG6kLP2P9DqE_cT_A6Vig3LACa2NlBPfg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAMRB3rKxqQpJ9kBGvUFPz3OAcv9bPRNzb9kyrYcpudJEAiEA4mb-dcnlkA0hl3qW4wykzVCKRSmZJZvQREPWm70SwEw%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: error: updateQueue error: null Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 20ms Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand addid "https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaNaPLOL26dsPqriZqA0&ip=94.172.95.126&id=o-AP9aVP4Wo7NnXKY5m2i09Cl27k2l78mXKsJX5cZ9We4n&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lne6&ms=au%2Crdu&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSWDjjIqn0euc1_-iz4Y46y7I9cAGu_iB2jF7aUTgSo3AyxP5Aj15HCAdqYYpz5X_IYdgqrObB8h&spc=hcYD5XmyQjzd_JMtKns8JG4UQhvG8g3muGgBcHLhxE9_dZuiF56Iqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1gaRyJAluahHqtMktUED_7UQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=Yd6TxSKQ-YMVeQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAPbMhMLp6y2P3I07RMSizY5ADXzWzq59nDTThw0AzaHWAiAYBt2702jkVfG6kLP2P9DqE_cT_A6Vig3LACa2NlBPfg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAMRB3rKxqQpJ9kBGvUFPz3OAcv9bPRNzb9kyrYcpudJEAiEA4mb-dcnlkA0hl3qW4wykzVCKRSmZJZvQREPWm70SwEw%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" took 19 milliseconds Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 19ms Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 18ms Sep 18 08:02:45 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:02:45 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:02:45 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 8ms Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 4ms Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 3ms Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 38ms Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand play took 19 milliseconds Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 24ms Sep 18 08:02:45 volumio volumio[1047]: info: ------------------------------ 19ms Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 08:02:45 volumio mpd[1391]: player: played "https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaNaPLOL26dsPqriZqA0&ip=94.172.95.126&id=o-AP9aVP4Wo7NnXKY5m2i09Cl27k2l78mXKsJX5cZ9We4n&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lne6&ms=au%2Crdu&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSWDjjIqn0euc1_-iz4Y46y7I9cAGu_iB2jF7aUTgSo3AyxP5Aj15HCAdqYYpz5X_IYdgqrObB8h&spc=hcYD5XmyQjzd_JMtKns8JG4UQhvG8g3muGgBcHLhxE9_dZuiF56Iqg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1gaRyJAluahHqtMktUED_7UQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=Yd6TxSKQ-YMVeQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAPbMhMLp6y2P3I07RMSizY5ADXzWzq59nDTThw0AzaHWAiAYBt2702jkVfG6kLP2P9DqE_cT_A6Vig3LACa2NlBPfg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAMRB3rKxqQpJ9kBGvUFPz3OAcv9bPRNzb9kyrYcpudJEAiEA4mb-dcnlkA0hl3qW4wykzVCKRSmZJZvQREPWm70SwEw%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand stop took 10 milliseconds Sep 18 08:02:45 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:45 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:45 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:45 volumio volumio[1047]: info: Sep 18 08:02:45 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:45 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand status took 13 milliseconds Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand clear took 15 milliseconds Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand status took 11 milliseconds Sep 18 08:02:45 volumio volumio[1047]: info: sendMpdCommand status took 9 milliseconds Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:45 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:45 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:45 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:45 volumio volumio[1047]: 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} Sep 18 08:02:45 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:45 volumio volumio[1047]: info: No code Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:45 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:45 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:45 volumio volumio[1047]: 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} Sep 18 08:02:45 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:45 volumio volumio[1047]: info: No code Sep 18 08:02:45 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:45 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 96ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 93ms Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: error: updateQueue error: null Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 95ms Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 81 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand addid "https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" took 80 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 6ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 5ms Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:46 volumio volumio[1047]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Sep 18 08:02:46 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:02:46 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:02:46 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 113ms Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 20ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 15ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 14ms Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:02:46 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 7ms Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand play took 5 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 5ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 3ms Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:02:46 volumio mpd[1391]: ffmpeg/opus: Could not update timestamps for skipped samples. Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:46 volumio volumio[1047]: info: Sep 18 08:02:46 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand status took 10 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand status took 7 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand status took 7 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:46 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:46 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:46 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 22ms Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 18 08:02:46 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:46 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:46 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:46 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:46 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:02:46 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:46 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:46 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:46 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:02:46 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:46 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:46 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:46 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:02:46 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:46 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:46 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:46 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:02:46 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:46 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:46 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:46 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:02:46 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:46 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 94ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 95ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 96ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 93ms Sep 18 08:02:46 volumio volumio[1047]: info: ------------------------------ 93ms Sep 18 08:02:49 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Sep 18 08:02:49 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d16330 00000000 00000000 0e000000 02028000 Sep 18 08:02:51 volumio volumio[1047]: info: BOOT COMPLETED Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::servicePause Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::servicePause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::pause Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand pause Sep 18 08:02:55 volumio volumio[1047]: info: Sep 18 08:02:55 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand pause took 21 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:55 volumio volumio[1047]: info: Sep 18 08:02:55 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:55 volumio volumio[1047]: info: Sep 18 08:02:55 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand status took 10 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand status took 6 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8276,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:55 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState stateService pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8276,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:55 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState stateService pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8276,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:55 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState stateService pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Sep 18 08:02:55 volumio volumio[1047]: info: ------------------------------ 77ms Sep 18 08:02:55 volumio volumio[1047]: info: ------------------------------ 73ms Sep 18 08:02:55 volumio volumio[1047]: info: ------------------------------ 85ms Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::play index undefined Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::resume Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Sep 18 08:02:55 volumio volumio[1047]: info: Sep 18 08:02:55 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand play took 5 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:55 volumio volumio[1047]: info: Sep 18 08:02:55 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:55 volumio volumio[1047]: info: Sep 18 08:02:55 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand status took 9 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand status took 6 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 18 08:02:55 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:55 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8518,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:55 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus pause Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8518,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:55 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:02:55 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:02:55 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:02:55 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8518,"duration":3436,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DEEP BASS & BEST VOICE - HI RES MUSIC 24 BIT - NATURAL BEAT RECORDS","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:02:55 volumio volumio[1047]: verbose: CURRENT POSITION 0 Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:02:55 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:02:55 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:02:55 volumio volumio[1047]: info: ------------------------------ 115ms Sep 18 08:02:55 volumio volumio[1047]: info: ------------------------------ 112ms Sep 18 08:02:55 volumio volumio[1047]: info: ------------------------------ 111ms Sep 18 08:03:01 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::play index 16 Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::stop Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::serviceStop Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:03:03 volumio volumio[1047]: info: ControllerMpd::stop Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 08:03:03 volumio mpd[1391]: ffmpeg/matroska,webm: File ended prematurely Sep 18 08:03:03 volumio mpd[1391]: player: played "https://rr1---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196964&ei=hKDLaMvoMrmezPsP39nysA4&ip=94.172.95.126&id=o-ALjFAk4q5_uJHbU6vMgY4Gz_-_1jaHQbJ5N8Iv_fSq6h&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175364%2C&mh=Rm&mm=31%2C26&mn=sn-f5f7knee%2Csn-i5h7lnls&ms=au%2Conr&mv=m&mvi=1&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSU8kFnTkaavdNoch-Fqu8S3U7XPV6L9fkqk8QETwNjOqPs2-lqiNT0Wr2Ywii6whFEoOI3rVv6D&spc=hcYD5Zdc1oHkjzIuMKVXcR9GYcawmL3AxS6yHfQZ8e06Am-2-2Mgxw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=1Zh7kEYwdUdhz85yYDDGy7oQ&rqh=1&gir=yes&clen=57721742&dur=3436.021&lmt=1735808076693880&mt=1758174844&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=IvsjLhEVGrx7eg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgAhvEByk-y2v2Cryef_zQmUb4FH8l5FprGWq2oIMKTqMCIFCapI6SWxs0r1zdj2mn__J3QrAZZ-yZm_G1UG029uB1&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANQFA_-K-c1GhK-QtQ2ho6Pa9IjFPh04tu_-MzMtngqwAiEAzNgiftD6LOmhNn14OJyHG0mrFiEV6HW7r64jQ5wg1Bo%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand stop took 129 milliseconds Sep 18 08:03:03 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::play index undefined Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22qFrgUqKQAKM%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A16%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Vintage%20Audio%20-%20C%E1%BA%A7n%20Th%C6%A1%20Audio%20-%20Audiophile%20Journey%20-%20Natural%20Beat%20Records%22%2C%22artist%22%3A%22Natural%20Beat%20Records%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FqFrgUqKQAKM%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB1AaAAuADigIMCAAQARhyIEwoNDAP%26rs%3DAOn4CLB4J88qZFGHlgwjhIxU1iyYmEvjjQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22qFrgUqKQAKM%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A16%7D%7D%7D Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:03 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:03 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand status took 22 milliseconds Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:03 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Sep 18 08:03:03 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Sep 18 08:03:03 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:03 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Sep 18 08:03:03 volumio volumio[1047]: info: ------------------------------ 59ms Sep 18 08:03:03 volumio volumio[1047]: info: ------------------------------ 53ms Sep 18 08:03:03 volumio volumio[1047]: info: ------------------------------ 44ms Sep 18 08:03:03 volumio volumio[1047]: warn: [youtube2] Error getting stream with default client in VideoModel.getInfo(qFrgUqKQAKM): No valid URL to decipher - retry with 'WEB_EMBEDDED' client. Sep 18 08:03:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand stop took 0 milliseconds Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:03 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand clear took 3 milliseconds Sep 18 08:03:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:03 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:03 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:03 volumio volumio[1047]: error: updateQueue error: null Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:03 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:03 volumio volumio[1047]: info: ------------------------------ 16ms Sep 18 08:03:03 volumio volumio[1047]: info: sendMpdCommand addid "https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" took 12 milliseconds Sep 18 08:03:03 volumio volumio[1047]: info: ------------------------------ 15ms Sep 18 08:03:03 volumio volumio[1047]: info: ------------------------------ 14ms Sep 18 08:03:03 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:03:03 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:03:03 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:03 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:03 volumio volumio[1047]: info: Sep 18 08:03:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:04 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:04 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 21ms Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 12ms Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 13ms Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:04 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:04 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 13ms Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand play took 8 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 8ms Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 8ms Sep 18 08:03:04 volumio mpd[1391]: ffmpeg/opus: Could not update timestamps for skipped samples. Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand status took 8 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand status took 10 milliseconds Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:04 volumio volumio[1047]: info: Sep 18 08:03:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand status took 22 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand status took 18 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand status took 7 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vintage Audio - Cần Thơ Audio - Audiophile Journey - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:04 volumio volumio[1047]: verbose: CURRENT POSITION 16 Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vintage Audio - Cần Thơ Audio - Audiophile Journey - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:04 volumio volumio[1047]: verbose: CURRENT POSITION 16 Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 62ms Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 65ms Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 43 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 18 08:03:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vintage Audio - Cần Thơ Audio - Audiophile Journey - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:04 volumio volumio[1047]: verbose: CURRENT POSITION 16 Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vintage Audio - Cần Thơ Audio - Audiophile Journey - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:04 volumio volumio[1047]: verbose: CURRENT POSITION 16 Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vintage Audio - Cần Thơ Audio - Audiophile Journey - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:04 volumio volumio[1047]: verbose: CURRENT POSITION 16 Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":455,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vintage Audio - Cần Thơ Audio - Audiophile Journey - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:04 volumio volumio[1047]: verbose: CURRENT POSITION 16 Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 161ms Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 160ms Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 150ms Sep 18 08:03:04 volumio volumio[1047]: info: ------------------------------ 145ms Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::play index 34 Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::stop Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::serviceStop Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 16 Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:03:12 volumio volumio[1047]: info: ControllerMpd::stop Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 08:03:12 volumio mpd[1391]: ffmpeg/matroska,webm: File ended prematurely Sep 18 08:03:12 volumio mpd[1391]: player: played "https://rr5---sn-f5f7knee.googlevideo.com/videoplayback?expire=1758196983&ei=l6DLaITCNIik6dsPobTTWA&ip=94.172.95.126&id=o-ACPVQn7gvzmay7ZUoXUI8UIykvnpwvPSGabeNj7lXKRv&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175383%2C&mh=VR&mm=31%2C29&mn=sn-f5f7knee%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=5&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVoleyqRIj4LRtrhZO0zo1a1eJdDWK3vpVevbGnKkwZtCYMnHndkWJRpnWKlTB3z3Ya7mZ6Y_XX&spc=hcYD5f9M59qnAgsB5RZ15eUjGqrJnB14jeWur0W1XokG0R35ZgkT4Q&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=tOyjcy8zTI0PVJ29BtG7kXwQ&rqh=1&gir=yes&clen=7795794&dur=454.541&lmt=1634648899249772&mt=1758174844&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=6311222&n=kLGjB5JChjpnVA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgTnd0gg4qQ5s8XiQOnrxdfY7Vvxgjp5vpTy7hW48tF9gCIEsPR_b1-x1oVUdd0VE0wX55cERA0QfwmA8z70rA088q&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgY8_zl83AjOCqzNnGrwWamRBkp6YVSkne_TpqL7q2aagCIHWyKGZOtgcIndCsfC_-PZJFc5fBEylTSKLl9t4pau0T&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" Sep 18 08:03:12 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 18 08:03:12 volumio dbus-daemon[715]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=1732 comm="timedatectl show --property=NTPSynchronized --valu") Sep 18 08:03:12 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand stop took 61 milliseconds Sep 18 08:03:12 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::play index undefined Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lz6cXN_Qtqg%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A34%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Natural%20Sound%20-%20Hi%20Res%20Audiophile%20Collection%202022%20-%20Natural%20Beat%20Records%22%2C%22artist%22%3A%22Natural%20Beat%20Records%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Flz6cXN_Qtqg%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gmAAtAFigIMCAAQARhyIEwoMTAP%26rs%3DAOn4CLCoFKZvl5oFGpXIC5u6NQPLhY5eaw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lz6cXN_Qtqg%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A34%7D%7D%7D Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:12 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:12 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand status took 83 milliseconds Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand status took 32 milliseconds Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand status took 7 milliseconds Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:12 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Sep 18 08:03:12 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:12 volumio dbus-daemon[715]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 18 08:03:12 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 18 08:03:12 volumio setdatetime-helper.sh[1731]: Time is not synchronized. Attempting to sync... Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Sep 18 08:03:12 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 228ms Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 178ms Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 152ms Sep 18 08:03:12 volumio sudo[1742]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 18 Sep 2025 06:03:12 GMT#015' Sep 18 08:03:12 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 18 08:03:12 volumio setdatetime-helper.sh[1743]: Thu Sep 18 08:03:12 CEST 2025 Sep 18 08:03:12 volumio setdatetime-helper.sh[1731]: Time synchronized successfully. Sep 18 08:03:12 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Sep 18 08:03:12 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 18 08:03:12 volumio systemd-journald[338]: Time jumped backwards, rotating. Sep 18 08:03:12 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 18 08:03:12 volumio volumio[1047]: warn: [youtube2] Error getting stream with default client in VideoModel.getInfo(lz6cXN_Qtqg): No valid URL to decipher - retry with 'WEB_EMBEDDED' client. Sep 18 08:03:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand stop took 0 milliseconds Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand clear took 0 milliseconds Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: error: updateQueue error: null Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 7ms Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand addid "https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube" took 2 milliseconds Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 2ms Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 1ms Sep 18 08:03:12 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:03:12 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:03:12 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 13ms Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 12ms Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 2ms Sep 18 08:03:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:03:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: Sep 18 08:03:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Sep 18 08:03:12 volumio volumio[1047]: info: Ignoring MPD Status Update Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 8ms Sep 18 08:03:12 volumio volumio[1047]: info: sendMpdCommand play took 7 milliseconds Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 2ms Sep 18 08:03:12 volumio volumio[1047]: info: ------------------------------ 1ms Sep 18 08:03:13 volumio mpd[1391]: ffmpeg/opus: Could not update timestamps for skipped samples. Sep 18 08:03:13 volumio volumio[1047]: info: Sep 18 08:03:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:13 volumio volumio[1047]: info: Sep 18 08:03:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:13 volumio volumio[1047]: info: Sep 18 08:03:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:13 volumio volumio[1047]: info: Sep 18 08:03:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand status took 7 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:13 volumio volumio[1047]: info: Sep 18 08:03:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:13 volumio volumio[1047]: info: Sep 18 08:03:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand status took 22 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand status took 16 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand status took 16 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:13 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Sep 18 08:03:13 volumio volumio[1047]: info: ------------------------------ 26ms Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand status took 10 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand status took 9 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:13 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:13 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:13 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: ------------------------------ 161ms Sep 18 08:03:13 volumio volumio[1047]: info: ------------------------------ 160ms Sep 18 08:03:13 volumio volumio[1047]: info: ------------------------------ 157ms Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 125 milliseconds Sep 18 08:03:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 125 milliseconds Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":238,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:13 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":238,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:13 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:13 volumio volumio[1047]: info: ------------------------------ 226ms Sep 18 08:03:13 volumio volumio[1047]: info: ------------------------------ 225ms Sep 18 08:03:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioSeek Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::seek Sep 18 08:03:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:15 volumio volumio[1047]: info: TRACKBLOCK {"service":"youtube2","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lz6cXN_Qtqg%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A34%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Natural%20Sound%20-%20Hi%20Res%20Audiophile%20Collection%202022%20-%20Natural%20Beat%20Records%22%2C%22artist%22%3A%22Natural%20Beat%20Records%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Flz6cXN_Qtqg%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB_gmAAtAFigIMCAAQARhyIEwoMTAP%26rs%3DAOn4CLCoFKZvl5oFGpXIC5u6NQPLhY5eaw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22lz6cXN_Qtqg%22%2C%22playlistId%22%3A%22PLDLX-8pj32TV0INGW8HH7OIN4x1mDa30Q%22%2C%22params%22%3A%22OAI%253D%22%2C%22index%22%3A34%7D%7D%7D","albumart":"https://i.ytimg.com/vi/lz6cXN_Qtqg/maxresdefault.jpg?sqp=-oaymwEmCIAKENAF8quKqQMa8AEB-AH-CYAC0AWKAgwIABABGHIgTCgxMA8=&rs=AOn4CLC3V-2eesEVZ4zrVooOA_W93cwlWQ","artist":"Natural Beat Records","album":"YouTube","name":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","duration":2865,"samplerate":"160 kbps"} Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Sep 18 08:03:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 08:03:15 volumio volumio[1047]: info: ControllerMpd::seek Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:15 volumio mpd[1391]: ffmpeg/matroska,webm: File ended prematurely Sep 18 08:03:15 volumio volumio[1047]: error: null Sep 18 08:03:15 volumio volumio[1047]: info: Sep 18 08:03:15 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:15 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:15 volumio volumio[1047]: info: Sep 18 08:03:15 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:15 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:15 volumio volumio[1047]: info: Sep 18 08:03:15 volumio volumio[1047]: ---------------------------- MPD announces state update: player Sep 18 08:03:15 volumio volumio[1047]: info: ControllerMpd::getState Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Sep 18 08:03:15 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Sep 18 08:03:15 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Sep 18 08:03:15 volumio volumio[1047]: info: sendMpdCommand status took 13 milliseconds Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::parseState Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 08:03:15 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 18 08:03:15 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 18 08:03:15 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:15 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Sep 18 08:03:15 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:15 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1502000,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:15 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:15 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:15 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:15 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1502000,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:15 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:15 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:15 volumio volumio[1047]: info: ControllerMpd::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Sep 18 08:03:15 volumio volumio[1047]: info: CorePlayQueue::getTrack 34 Sep 18 08:03:15 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1502000,"duration":2865,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Natural Sound - Hi Res Audiophile Collection 2022 - Natural Beat Records","artist":"Natural Beat Records","album":"YouTube","uri":"https://rr3---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1758196993&ei=oaDLaKr5Ebeo6dsPnLPUMQ&ip=94.172.95.126&id=o-ACx5ZyVpNKeUE3tyzOrT0uDpnkRPCbchlRqETsNt2rcB&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1758175393%2C&mh=IK&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=18&rms=au%2Cau&initcwndbps=3511250&bui=ATw7iSVLbfyEmRkPNnp6icu6V-U6B_9H1WuoC8WcPX6To8EJfq2_7YUsPGs_EMsQo005kgZuHLGNZuqp&spc=hcYD5SNYIYMZRHhTI6mIRuBn9j92ehYW231j3yj9q5ky7ZN5npD8yQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=aJ1aHEIeekyNEDK5k-ETglMQ&rqh=1&gir=yes&clen=49605162&dur=2864.821&lmt=1651928292781116&mt=1758174844&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5432434&n=nSCHYb9_f0PRIg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP_4vpmcHBf--H7LulXePpXe_P_DK4xCl3B1qEq5zEPnAiBGu8slDwl4LWrMQCZfiSiGVJLKd3bwV2Yh9rmnLgoFhg%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ5PRa972teSECWedYQM4BbIlgec7kjwLgNxxrilWRUGAiEAn4RRIP8-dnq5_lyex8Q9SXtqLIpe7twd52uF3RuNH9w%3D&pot=MpoBg0saTIrZKEyDdbZ57_nVZ3MuQWOxwmqfHE-Qm_EsQS59U8jGssXx2eUF_0omeKn0x4GSTt2hiNwKdWHynA_h58tx5crnd3cU09kllxVUGFqXdbE6Cy-dyDJQW5zIAw1r5-Plpzk8tbbkrRO-EG53e41iru8I7sObEkGAJzTXN1LgrTAXmbO-6Ygqe9sRzIukvms1R3oktmW3eA%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Sep 18 08:03:15 volumio volumio[1047]: verbose: CURRENT POSITION 34 Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Sep 18 08:03:15 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreStateMachine::pushState Sep 18 08:03:15 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Sep 18 08:03:15 volumio volumio[1047]: info: ------------------------------ 127ms Sep 18 08:03:15 volumio volumio[1047]: info: ------------------------------ 127ms Sep 18 08:03:15 volumio volumio[1047]: info: ------------------------------ 126ms Sep 18 08:03:17 volumio mpd[1391]: ffmpeg/opus: Could not update timestamps for skipped samples. Sep 18 08:03:21 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 18 08:03:21 volumio volumio[1047]: info: Preload queue cleared Sep 18 08:03:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 18 08:03:25 volumio volumio[1047]: Use XMLElement.setAttribute instead Sep 18 08:03:25 volumio volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 08:03:25 volumio volumio[1047]: TypeError: children[i].attr(...).value is not a function Sep 18 08:03:25 volumio volumio[1047]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Sep 18 08:03:25 volumio volumio[1047]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 18 08:03:25 volumio volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 08:03:25 volumio sudo[1822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-18 08:02' Sep 18 08:03:25 volumio sudo[1822]: 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"