May 29 13:54:00 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 13:54:00 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 13:54:00 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 13:54:00 volumio2 volumio-remote-updater[633]: No test mode May 29 13:54:00 volumio2 volumio-remote-updater[633]: Alpha test mode enabled May 29 13:54:00 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 13:54:00 volumio2 volumio[1026]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 13:54:00 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 13:54:01 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 13:54:01 volumio2 volumio[1026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 79 May 29 13:54:01 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:01 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:06 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 13:54:06 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 13:54:06 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 13:54:06 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 13:54:06 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 13:54:06 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources May 29 13:54:06 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 13:54:07 volumio2 volumio[1026]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 29 13:54:07 volumio2 volumio[1026]: info: Completed starting MyVolumio Plugin May 29 13:54:07 volumio2 volumio[1026]: [Metrics] CommandRouter: 46s 126.31ms May 29 13:54:07 volumio2 volumio[1026]: info: CoreCommandRouter::volumiosetStartupVolume May 29 13:54:07 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 13:54:07 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 13:54:07 volumio2 volumio[1026]: info: CoreCommandRouter::Close All Modals sent May 29 13:54:07 volumio2 volumio[1026]: info: CoreCommandRouter::Close All Modals sent May 29 13:54:08 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 29 13:54:08 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 13:54:08 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 29 13:54:08 volumio2 volumio[1026]: info: Version has changed, forcing UI Reload May 29 13:54:08 volumio2 volumio[1026]: info: CoreCommandRouter::Reload Ui May 29 13:54:09 volumio2 volumio[1026]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 79 May 29 13:54:09 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:09 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:09 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetQueue May 29 13:54:09 volumio2 volumio[1026]: info: CoreStateMachine::getQueue May 29 13:54:09 volumio2 volumio[1026]: info: CorePlayQueue::getQueue May 29 13:54:09 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid May 29 13:54:09 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel May 29 13:54:09 volumio2 volumio[1026]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] May 29 13:54:09 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction May 29 13:54:09 volumio2 volumio[1026]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs May 29 13:54:09 volumio2 volumio[1026]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] May 29 13:54:09 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection May 29 13:54:14 volumio2 volumio[1026]: info: BOOT COMPLETED May 29 13:54:23 volumio2 sudo[1577]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 13:54:23 volumio2 sudo[1577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:23 volumio2 sudo[1577]: pam_unix(sudo:session): session closed for user root May 29 13:54:23 volumio2 sudo[1579]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 13:54:23 volumio2 sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:23 volumio2 sudo[1579]: pam_unix(sudo:session): session closed for user root May 29 13:54:23 volumio2 volumio[1026]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 79 May 29 13:54:23 volumio2 sudo[1585]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 13:54:23 volumio2 sudo[1587]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 13:54:23 volumio2 sudo[1587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:23 volumio2 sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:23 volumio2 sudo[1587]: pam_unix(sudo:session): session closed for user root May 29 13:54:23 volumio2 sudo[1585]: pam_unix(sudo:session): session closed for user root May 29 13:54:24 volumio2 volumio[1026]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 79 May 29 13:54:24 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:24 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:24 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 13:54:24 volumio2 volumio[1026]: info: Listing playlists May 29 13:54:25 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 13:54:25 volumio2 volumio[1026]: info: Received Get System Info May 29 13:54:25 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 13:54:25 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 13:54:25 volumio2 volumio[1026]: info: Discovery: Getting this device information May 29 13:54:25 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:25 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:25 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 13:54:26 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 13:54:26 volumio2 volumio[1026]: info: Received Get System Info May 29 13:54:26 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 13:54:26 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 13:54:26 volumio2 volumio[1026]: info: Discovery: Getting this device information May 29 13:54:26 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:26 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:26 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 13:54:26 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPlay May 29 13:54:26 volumio2 volumio[1026]: info: CoreStateMachine::play index undefined May 29 13:54:26 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 13:54:26 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:26 volumio2 volumio[1026]: info: CoreStateMachine::startPlaybackTimer May 29 13:54:26 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:26 volumio2 volumio[1026]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226vNnB4oLZNo%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AxwSGlJERU1SN242T1hMSEE4bndwQ3dQbl8tTUZnugMKCNP4zoOBsfved7oDHBIaUkRFTVI3bjZPWExIQThud3BDd1BuXy1NRme6AwoIiqfqg63JsLQdugMLCJavzt72u-3VigG6AwsIhoP-5sGd7Y_CAboDCgiIrqbtzc3Ez0W6AwsIsKW1vfXw2N3BAboDCwjG6bKOjemmxqoBugMLCNr4pd6Y_c_rsQG6AwoIysvo0cGcls1SugMkEiJQTHVLS2FMb3dlZlZoNldianF3TjJ0VDJoZklHTW9xbnFNugMKCKuxyIWgnIv8B7oDCgjHn9DBg9Ddti66AwoIuPvL1NOiid4OugMKCMP_mYzZv5_bfroDCgj1rPz04-zB8gK6AwoIluS6j8ei8cNaugMLCMWjkavb2OmQ4QHyAwUN8Yf2PQ%253D%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Ace%20of%20Base%20-%20Happy%20Nation%20(Lyrics)%22%2C%22artist%22%3A%22Cassiopeia%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F6vNnB4oLZNo%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBK0vMMxtWUox2e3XdMGPyuG_lXIg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226vNnB4oLZNo%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AxwSGlJERU1SN242T1hMSEE4bndwQ3dQbl8tTUZnugMKCNP4zoOBsfved7oDHBIaUkRFTVI3bjZPWExIQThud3BDd1BuXy1NRme6AwoIiqfqg63JsLQdugMLCJavzt72u-3VigG6AwsIhoP-5sGd7Y_CAboDCgiIrqbtzc3Ez0W6AwsIsKW1vfXw2N3BAboDCwjG6bKOjemmxqoBugMLCNr4pd6Y_c_rsQG6AwoIysvo0cGcls1SugMkEiJQTHVLS2FMb3dlZlZoNldianF3TjJ0VDJoZklHTW9xbnFNugMKCKuxyIWgnIv8B7oDCgjHn9DBg9Ddti66AwoIuPvL1NOiid4OugMKCMP_mYzZv5_bfroDCgj1rPz04-zB8gK6AwoIluS6j8ei8cNaugMLCMWjkavb2OmQ4QHyAwUN8Yf2PQ%253D%253D%22%7D%7D%7D May 29 13:54:26 volumio2 volumio[1026]: info: [youtube2] InnertubeLoader: creating Innertube instance... May 29 13:54:37 volumio2 volumio[1026]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... May 29 13:54:38 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources May 29 13:54:38 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 13:54:38 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetQueue May 29 13:54:38 volumio2 volumio[1026]: info: CoreStateMachine::getQueue May 29 13:54:38 volumio2 volumio[1026]: info: CorePlayQueue::getQueue May 29 13:54:39 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 13:54:39 volumio2 dbus-daemon[624]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=1619 comm="timedatectl show --property=NTPSynchronized --valu") May 29 13:54:39 volumio2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 13:54:39 volumio2 dbus-daemon[624]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 13:54:39 volumio2 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 13:54:39 volumio2 setdatetime-helper.sh[1618]: Time is not synchronized. Attempting to sync... May 29 13:54:39 volumio2 sudo[1627]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 12:54:40 GMT#015' May 29 13:54:39 volumio2 sudo[1627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 13:54:40 volumio2 setdatetime-helper.sh[1628]: Thu May 29 13:54:40 +01 2025 May 29 13:54:40 volumio2 sudo[1627]: pam_unix(sudo:session): session closed for user root May 29 13:54:40 volumio2 setdatetime-helper.sh[1618]: Time synchronized successfully. May 29 13:54:40 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 13:54:40 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 13:54:40 volumio2 volumio[1026]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) May 29 13:54:40 volumio2 volumio[1026]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token May 29 13:54:40 volumio2 volumio[1026]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... May 29 13:54:41 volumio2 sudo[1630]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 13:54:41 volumio2 sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:41 volumio2 sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 13:54:41 volumio2 sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:41 volumio2 sudo[1630]: pam_unix(sudo:session): session closed for user root May 29 13:54:41 volumio2 sudo[1632]: pam_unix(sudo:session): session closed for user root May 29 13:54:41 volumio2 volumio[1026]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 79 May 29 13:54:41 volumio2 sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 13:54:41 volumio2 sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:41 volumio2 sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 13:54:41 volumio2 sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:54:41 volumio2 sudo[1636]: pam_unix(sudo:session): session closed for user root May 29 13:54:41 volumio2 sudo[1638]: pam_unix(sudo:session): session closed for user root May 29 13:54:41 volumio2 volumio[1026]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 79 May 29 13:54:41 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:41 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:41 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 13:54:41 volumio2 volumio[1026]: info: Listing playlists May 29 13:54:41 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 13:54:41 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 29 13:54:41 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 29 13:54:41 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 13:54:49 volumio2 volumio[1026]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 13:54:49 volumio2 volumio[1026]: info: Received Get System Info May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 13:54:49 volumio2 volumio[1026]: info: Discovery: Getting this device information May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:49 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPlay May 29 13:54:49 volumio2 volumio[1026]: info: CoreStateMachine::play index undefined May 29 13:54:49 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 13:54:49 volumio2 volumio[1026]: info: Received Get System Info May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 13:54:49 volumio2 volumio[1026]: info: Discovery: Getting this device information May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:49 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 13:54:49 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetQueue May 29 13:54:49 volumio2 volumio[1026]: info: CoreStateMachine::getQueue May 29 13:54:49 volumio2 volumio[1026]: info: CorePlayQueue::getQueue May 29 13:54:49 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:49 volumio2 volumio[1026]: info: CoreStateMachine::startPlaybackTimer May 29 13:54:49 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:49 volumio2 volumio[1026]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226vNnB4oLZNo%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AxwSGlJERU1SN242T1hMSEE4bndwQ3dQbl8tTUZnugMKCNP4zoOBsfved7oDHBIaUkRFTVI3bjZPWExIQThud3BDd1BuXy1NRme6AwoIiqfqg63JsLQdugMLCJavzt72u-3VigG6AwsIhoP-5sGd7Y_CAboDCgiIrqbtzc3Ez0W6AwsIsKW1vfXw2N3BAboDCwjG6bKOjemmxqoBugMLCNr4pd6Y_c_rsQG6AwoIysvo0cGcls1SugMkEiJQTHVLS2FMb3dlZlZoNldianF3TjJ0VDJoZklHTW9xbnFNugMKCKuxyIWgnIv8B7oDCgjHn9DBg9Ddti66AwoIuPvL1NOiid4OugMKCMP_mYzZv5_bfroDCgj1rPz04-zB8gK6AwoIluS6j8ei8cNaugMLCMWjkavb2OmQ4QHyAwUN8Yf2PQ%253D%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Ace%20of%20Base%20-%20Happy%20Nation%20(Lyrics)%22%2C%22artist%22%3A%22Cassiopeia%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F6vNnB4oLZNo%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBK0vMMxtWUox2e3XdMGPyuG_lXIg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%226vNnB4oLZNo%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AxwSGlJERU1SN242T1hMSEE4bndwQ3dQbl8tTUZnugMKCNP4zoOBsfved7oDHBIaUkRFTVI3bjZPWExIQThud3BDd1BuXy1NRme6AwoIiqfqg63JsLQdugMLCJavzt72u-3VigG6AwsIhoP-5sGd7Y_CAboDCgiIrqbtzc3Ez0W6AwsIsKW1vfXw2N3BAboDCwjG6bKOjemmxqoBugMLCNr4pd6Y_c_rsQG6AwoIysvo0cGcls1SugMkEiJQTHVLS2FMb3dlZlZoNldianF3TjJ0VDJoZklHTW9xbnFNugMKCKuxyIWgnIv8B7oDCgjHn9DBg9Ddti66AwoIuPvL1NOiid4OugMKCMP_mYzZv5_bfroDCgj1rPz04-zB8gK6AwoIluS6j8ei8cNaugMLCMWjkavb2OmQ4QHyAwUN8Yf2PQ%253D%253D%22%7D%7D%7D May 29 13:54:50 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:50 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand stop May 29 13:54:50 volumio2 volumio[1026]: info: sendMpdCommand stop took 42 milliseconds May 29 13:54:50 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand clear May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: sendMpdCommand clear took 9 milliseconds May 29 13:54:50 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544889&ei=GVk4aIbjI4_Ui9oP17uBwQk&ip=109.250.158.145&id=o-AFXQoHRxn3eeaUT-2Bv0guWxlFUYFgZiNvOYxBsgcoBR&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jeenle&ms=au%2Crdu&mv=m&mvi=2&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLPX2nW7uvNROKj4zpeitSEVmqggYYdzPxFiiFFPcGClLOshmO9cQb0NabFCWITOb0YzdfSC_sUm&spc=l3OVKfc-ulSU8Bkv7RSPDGIdXgFCZPMjj2dM4iV-FQRM3qiNc1HShinhM9mf6kBdLGM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yBmG0tT0vLNC34UQCjjdxsMQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=4&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=nwH8sWmkgcrGYA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPtknmtz49ES4fTT1qY7uWh-KOq79afSA770Y5N1wlj0AiEAw2suPPpojwFTOHajgXQ31lcwALrboNV0l-QCuZa-TBY%3D&sig=AJfQdSswRgIhANIsSvYAVOeLwQWsh0CaQFPufWUR79fTxcalhF9MAOMaAiEAoLF75lKcXscpGBJbj-w-EN5UP8MzL7qClzi0csxNzjw%3D&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: error: updateQueue error: null May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 34ms May 29 13:54:50 volumio2 volumio[1026]: info: sendMpdCommand addid "https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544889&ei=GVk4aIbjI4_Ui9oP17uBwQk&ip=109.250.158.145&id=o-AFXQoHRxn3eeaUT-2Bv0guWxlFUYFgZiNvOYxBsgcoBR&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jeenle&ms=au%2Crdu&mv=m&mvi=2&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLPX2nW7uvNROKj4zpeitSEVmqggYYdzPxFiiFFPcGClLOshmO9cQb0NabFCWITOb0YzdfSC_sUm&spc=l3OVKfc-ulSU8Bkv7RSPDGIdXgFCZPMjj2dM4iV-FQRM3qiNc1HShinhM9mf6kBdLGM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yBmG0tT0vLNC34UQCjjdxsMQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=4&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=nwH8sWmkgcrGYA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPtknmtz49ES4fTT1qY7uWh-KOq79afSA770Y5N1wlj0AiEAw2suPPpojwFTOHajgXQ31lcwALrboNV0l-QCuZa-TBY%3D&sig=AJfQdSswRgIhANIsSvYAVOeLwQWsh0CaQFPufWUR79fTxcalhF9MAOMaAiEAoLF75lKcXscpGBJbj-w-EN5UP8MzL7qClzi0csxNzjw%3D&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" took 33 milliseconds May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 33ms May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 20ms May 29 13:54:50 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:54:50 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:54:50 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 39ms May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 18ms May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 17ms May 29 13:54:50 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 13:54:50 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand play May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: May 29 13:54:50 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:50 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 25ms May 29 13:54:50 volumio2 volumio[1026]: info: sendMpdCommand play took 23 milliseconds May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 13ms May 29 13:54:50 volumio2 volumio[1026]: info: ------------------------------ 12ms May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand stop May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri May 29 13:54:51 volumio2 volumio[1026]: info: [youtube2-browse] browseUri: youtube2 May 29 13:54:51 volumio2 mpd[1335]: player: played "https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544889&ei=GVk4aIbjI4_Ui9oP17uBwQk&ip=109.250.158.145&id=o-AFXQoHRxn3eeaUT-2Bv0guWxlFUYFgZiNvOYxBsgcoBR&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jeenle&ms=au%2Crdu&mv=m&mvi=2&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLPX2nW7uvNROKj4zpeitSEVmqggYYdzPxFiiFFPcGClLOshmO9cQb0NabFCWITOb0YzdfSC_sUm&spc=l3OVKfc-ulSU8Bkv7RSPDGIdXgFCZPMjj2dM4iV-FQRM3qiNc1HShinhM9mf6kBdLGM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yBmG0tT0vLNC34UQCjjdxsMQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=4&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=nwH8sWmkgcrGYA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAPtknmtz49ES4fTT1qY7uWh-KOq79afSA770Y5N1wlj0AiEAw2suPPpojwFTOHajgXQ31lcwALrboNV0l-QCuZa-TBY%3D&sig=AJfQdSswRgIhANIsSvYAVOeLwQWsh0CaQFPufWUR79fTxcalhF9MAOMaAiEAoLF75lKcXscpGBJbj-w-EN5UP8MzL7qClzi0csxNzjw%3D&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand stop took 257 milliseconds May 29 13:54:51 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand clear May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:51 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:51 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand status took 3 milliseconds May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand clear took 4 milliseconds May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand status took 3 milliseconds May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand status took 3 milliseconds May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:51 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:51 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:51 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 13:54:51 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService stop May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus stop May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:51 volumio2 volumio[1026]: info: No code May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:51 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:51 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:51 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 13:54:51 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService stop May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus stop May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:51 volumio2 volumio[1026]: info: No code May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:51 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 228ms May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 228ms May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: Preload queue cleared May 29 13:54:51 volumio2 volumio[1026]: info: Preload queue cleared May 29 13:54:51 volumio2 volumio[1026]: info: Preload queue cleared May 29 13:54:51 volumio2 volumio[1026]: error: updateQueue error: null May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 242ms May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 239 milliseconds May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand addid "https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" took 239 milliseconds May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 16ms May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 7ms May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:54:51 volumio2 volumio[1026]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 29 13:54:51 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:54:51 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:54:51 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 270ms May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:51 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:51 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:51 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:51 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:51 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 91ms May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 66ms May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 60ms May 29 13:54:51 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 13:54:51 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand play May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: May 29 13:54:51 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:54:51 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 16ms May 29 13:54:51 volumio2 volumio[1026]: info: sendMpdCommand play took 15 milliseconds May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 6ms May 29 13:54:51 volumio2 volumio[1026]: info: ------------------------------ 5ms May 29 13:54:52 volumio2 mpd[1335]: ffmpeg/opus: Could not update timestamps for skipped samples. May 29 13:54:52 volumio2 volumio[1026]: info: May 29 13:54:52 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:52 volumio2 volumio[1026]: info: May 29 13:54:52 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:52 volumio2 volumio[1026]: info: May 29 13:54:52 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:52 volumio2 volumio[1026]: info: May 29 13:54:52 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand status took 3 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:54:52 volumio2 volumio[1026]: info: May 29 13:54:52 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:52 volumio2 volumio[1026]: info: May 29 13:54:52 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand status took 22 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand status took 20 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand status took 21 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 4 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand status took 19 milliseconds May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:52 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:52 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - Happy Nation (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:54:52 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus stop May 29 13:54:52 volumio2 volumio[1026]: info: ------------------------------ 64ms May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand status took 41 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 21 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 22 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 22 milliseconds May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 21 milliseconds May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:52 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:52 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - Happy Nation (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:54:52 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:54:52 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:52 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:52 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - Happy Nation (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:54:52 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:54:52 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:52 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:52 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - Happy Nation (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:54:52 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:54:52 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:52 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:52 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - Happy Nation (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:54:52 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:54:52 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: ------------------------------ 262ms May 29 13:54:52 volumio2 volumio[1026]: info: ------------------------------ 261ms May 29 13:54:52 volumio2 volumio[1026]: info: ------------------------------ 262ms May 29 13:54:52 volumio2 volumio[1026]: info: ------------------------------ 243ms May 29 13:54:52 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 208 milliseconds May 29 13:54:52 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:54:52 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:54:52 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:54:52 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - Happy Nation (Lyrics)","artist":"Cassiopeia","album":"YouTube","uri":"https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:54:52 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:54:52 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:54:52 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:54:52 volumio2 volumio[1026]: info: ------------------------------ 287ms May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:52 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:54:57 volumio2 volumio[1026]: Searching plugin music_service/youtube2 May 29 13:54:57 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search May 29 13:54:58 volumio2 volumio[1026]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 29 13:54:58 volumio2 volumio[1026]: command_run: { startIndex: 0, length: 57, onTap: { innertubeCommand: [Object] } }, May 29 13:54:58 volumio2 volumio[1026]: input_data: { May 29 13:54:58 volumio2 volumio[1026]: content: 'Ace of Base - Happy Nation (Official Music Video) · 3:32', May 29 13:54:58 volumio2 volumio[1026]: commandRuns: [ [Object] ] May 29 13:54:58 volumio2 volumio[1026]: }, May 29 13:54:58 volumio2 volumio[1026]: parsed_runs: [ May 29 13:54:58 volumio2 volumio[1026]: { May 29 13:54:58 volumio2 volumio[1026]: text: 'Ace of Base - Happy Nation (Official Music Video) · 3:32', May 29 13:54:58 volumio2 volumio[1026]: startIndex: 0 May 29 13:54:58 volumio2 volumio[1026]: } May 29 13:54:58 volumio2 volumio[1026]: ] May 29 13:54:58 volumio2 volumio[1026]: } May 29 13:54:58 volumio2 volumio[1026]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { May 29 13:54:58 volumio2 volumio[1026]: command_run: { startIndex: 0, length: 63, onTap: { innertubeCommand: [Object] } }, May 29 13:54:58 volumio2 volumio[1026]: input_data: { May 29 13:54:58 volumio2 volumio[1026]: content: 'Ace of Base - All That She Wants (Official Music Video) · 3:34', May 29 13:54:58 volumio2 volumio[1026]: commandRuns: [ [Object] ] May 29 13:54:58 volumio2 volumio[1026]: }, May 29 13:54:58 volumio2 volumio[1026]: parsed_runs: [ May 29 13:54:58 volumio2 volumio[1026]: { May 29 13:54:58 volumio2 volumio[1026]: text: 'Ace of Base - All That She Wants (Official Music Video) · 3:34', May 29 13:54:58 volumio2 volumio[1026]: startIndex: 0 May 29 13:54:58 volumio2 volumio[1026]: } May 29 13:54:58 volumio2 volumio[1026]: ] May 29 13:54:58 volumio2 volumio[1026]: } May 29 13:54:58 volumio2 volumio[1026]: info: All search sources collected, pushing search results May 29 13:54:59 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 13:54:59 volumio2 volumio[1026]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 29 13:54:59 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 29 13:54:59 volumio2 volumio[1026]: info: Received Get System Version May 29 13:54:59 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 13:54:59 volumio2 volumio[1026]: info: Received Get System Info May 29 13:54:59 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 13:54:59 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 13:54:59 volumio2 volumio[1026]: info: Discovery: Getting this device information May 29 13:54:59 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:54:59 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 13:55:00 volumio2 volumio[1026]: info: Preload queue cleared May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::ClearQueue May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::stop May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::stPlaybackTimer May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::updateTrackBlock May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrackBlock May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::serviceStop May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::serviceStop May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 13:55:00 volumio2 volumio[1026]: info: ControllerMpd::stop May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand stop May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::clearPlayQueue May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::saveQueue May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushQueue May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::addQueueItems May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::addQueueItems May 29 13:55:00 volumio2 volumio[1026]: info: Preload queue cleared May 29 13:55:00 volumio2 volumio[1026]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Ace%20of%20Base%20-%20The%20Sign%20(1993)%20%5BFull%20Album%5D%22%2C%22artist%22%3A%22Ace%20of%20Base%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRZ8SbN2plwg%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLCpZXbPTZYcyb7jIbllRuI_j1tdHw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22RZ8SbN2plwg%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AwoI0_jOg4Gx-953ugMcEhpSREVNUjduNk9YTEhBOG53cEN3UG5fLU1GZ7oDCwiwpbW99fDY3cEBugMKCIqn6oOtybC0HboDCwiWr87e9rvt1YoBugMLCIaD_ubBne2PwgG6AwsIxumyjo3ppsaqAboDCwja-KXemP3P67EBugMKCMef0MGD0N22LroDCgirsciFoJyL_Ae6AwoIuPvL1NOiid4OugMLCMWjkavb2OmQ4QG6AwoIw_-ZjNm_n9t-ugMKCOG_joab1-qQLLoDCgiHwr_yotqr1ku6AwoIkY75we-6wPcNugMLCOn72vutl92lsQG6AwoIj-OIqoSUnMkV8gMFDfGH9j0%253D%22%7D%7D%7D May 29 13:55:00 volumio2 volumio[1026]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Ace%20of%20Base%20-%20The%20Sign%20(1993)%20%5BFull%20Album%5D%22%2C%22artist%22%3A%22Ace%20of%20Base%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRZ8SbN2plwg%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLCpZXbPTZYcyb7jIbllRuI_j1tdHw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22RZ8SbN2plwg%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AwoI0_jOg4Gx-953ugMcEhpSREVNUjduNk9YTEhBOG53cEN3UG5fLU1GZ7oDCwiwpbW99fDY3cEBugMKCIqn6oOtybC0HboDCwiWr87e9rvt1YoBugMLCIaD_ubBne2PwgG6AwsIxumyjo3ppsaqAboDCwja-KXemP3P67EBugMKCMef0MGD0N22LroDCgirsciFoJyL_Ae6AwoIuPvL1NOiid4OugMLCMWjkavb2OmQ4QG6AwoIw_-ZjNm_n9t-ugMKCOG_joab1-qQLLoDCgiHwr_yotqr1ku6AwoIkY75we-6wPcNugMLCOn72vutl92lsQG6AwoIj-OIqoSUnMkV8gMFDfGH9j0%253D%22%7D%7D%7D in service youtube2 May 29 13:55:00 volumio2 volumio[1026]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Ace%20of%20Base%20-%20The%20Sign%20(1993)%20%5BFull%20Album%5D%22%2C%22artist%22%3A%22Ace%20of%20Base%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRZ8SbN2plwg%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLCpZXbPTZYcyb7jIbllRuI_j1tdHw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22RZ8SbN2plwg%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AwoI0_jOg4Gx-953ugMcEhpSREVNUjduNk9YTEhBOG53cEN3UG5fLU1GZ7oDCwiwpbW99fDY3cEBugMKCIqn6oOtybC0HboDCwiWr87e9rvt1YoBugMLCIaD_ubBne2PwgG6AwsIxumyjo3ppsaqAboDCwja-KXemP3P67EBugMKCMef0MGD0N22LroDCgirsciFoJyL_Ae6AwoIuPvL1NOiid4OugMLCMWjkavb2OmQ4QG6AwoIw_-ZjNm_n9t-ugMKCOG_joab1-qQLLoDCgiHwr_yotqr1ku6AwoIkY75we-6wPcNugMLCOn72vutl92lsQG6AwoIj-OIqoSUnMkV8gMFDfGH9j0%253D%22%7D%7D%7D May 29 13:55:00 volumio2 mpd[1335]: ffmpeg/matroska,webm: File ended prematurely May 29 13:55:00 volumio2 mpd[1335]: player: played "https://rr2---sn-8xgn5uxa-4g56.googlevideo.com/videoplayback?expire=1748544890&ei=GVk4aJ2WMNuOi9oPyu6mgQY&ip=109.250.158.145&id=o-ABqSFzOhwd2HU4O5CM473jVPRZtG9rm9Q4jiTqk49zUE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523289%2C&mh=Lu&mm=31%2C29&mn=sn-8xgn5uxa-4g56%2Csn-h0jelnes&ms=au%2Crdu&mv=m&mvi=2&pcm2cms=yes&pl=22&rms=au%2Cau&initcwndbps=2842500&bui=AY1jyLOxCVdw98kCtsGoK16PQYi6XM8uwQY2YyGbK2rjJAPsHNPu_RuMAOYGa2IeA1plob-b2OAPD6RI&spc=l3OVKUG7mJH9PcMJh5aKoVpA85v5q_cWT05vzUkAYXXaGsMYa8xctjoed76IgrXonlA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Bd0Ail8EbCaJyHlV0w6BsnsQ&rqh=1&gir=yes&clen=3210045&dur=195.221&lmt=1732939259740335&mt=1748522949&fvip=2&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=5532434&n=a66dt2GWXKQk6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAMew40aWlW3WA9eRzI_WWr0CI5e1QQI38yzYgIRzhbtKAiAxEiv7eTbstQ01vuAQklIhxwvCIGd4XX6_6oL-FVgsCg%3D%3D&sig=AJfQdSswRAIgFTnTpWMni2g7sIn9ojjTJps648nNxBOJN613sy7Tg-cCIF-mql5oCc8KYKD7Tpf9q2ygQt79mFAdtWWWqNfboOsZ&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushQueue May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::saveQueue May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::updateTrackBlock May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrackBlock May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPlay May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::play index 0 May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::stop May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::play index undefined May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::startPlaybackTimer May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22RZ8SbN2plwg%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AwoI0_jOg4Gx-953ugMcEhpSREVNUjduNk9YTEhBOG53cEN3UG5fLU1GZ7oDCwiwpbW99fDY3cEBugMKCIqn6oOtybC0HboDCwiWr87e9rvt1YoBugMLCIaD_ubBne2PwgG6AwsIxumyjo3ppsaqAboDCwja-KXemP3P67EBugMKCMef0MGD0N22LroDCgirsciFoJyL_Ae6AwoIuPvL1NOiid4OugMLCMWjkavb2OmQ4QG6AwoIw_-ZjNm_n9t-ugMKCOG_joab1-qQLLoDCgiHwr_yotqr1ku6AwoIkY75we-6wPcNugMLCOn72vutl92lsQG6AwoIj-OIqoSUnMkV8gMFDfGH9j0%253D%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Ace%20of%20Base%20-%20The%20Sign%20(1993)%20%5BFull%20Album%5D%22%2C%22artist%22%3A%22Ace%20of%20Base%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRZ8SbN2plwg%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLCpZXbPTZYcyb7jIbllRuI_j1tdHw%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22RZ8SbN2plwg%22%2C%22params%22%3A%22qgMLYWNlIG9mIGJhc2W6AwoI0_jOg4Gx-953ugMcEhpSREVNUjduNk9YTEhBOG53cEN3UG5fLU1GZ7oDCwiwpbW99fDY3cEBugMKCIqn6oOtybC0HboDCwiWr87e9rvt1YoBugMLCIaD_ubBne2PwgG6AwsIxumyjo3ppsaqAboDCwja-KXemP3P67EBugMKCMef0MGD0N22LroDCgirsciFoJyL_Ae6AwoIuPvL1NOiid4OugMLCMWjkavb2OmQ4QG6AwoIw_-ZjNm_n9t-ugMKCOG_joab1-qQLLoDCgiHwr_yotqr1ku6AwoIkY75we-6wPcNugMLCOn72vutl92lsQG6AwoIj-OIqoSUnMkV8gMFDfGH9j0%253D%22%7D%7D%7D May 29 13:55:00 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:00 volumio2 volumio[1026]: info: May 29 13:55:00 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:00 volumio2 volumio[1026]: info: sendMpdCommand stop took 120 milliseconds May 29 13:55:00 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:00 volumio2 volumio[1026]: info: May 29 13:55:00 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:00 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:00 volumio2 volumio[1026]: info: May 29 13:55:00 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:00 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:00 volumio2 volumio[1026]: info: sendMpdCommand status took 6 milliseconds May 29 13:55:00 volumio2 volumio[1026]: info: sendMpdCommand status took 6 milliseconds May 29 13:55:00 volumio2 volumio[1026]: info: sendMpdCommand status took 5 milliseconds May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:00 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 13:55:00 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 2 milliseconds May 29 13:55:00 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:00 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:00 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 29 13:55:00 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 29 13:55:00 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:00 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:00 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:00 volumio2 volumio[1026]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd May 29 13:55:00 volumio2 volumio[1026]: info: ------------------------------ 82ms May 29 13:55:00 volumio2 volumio[1026]: info: ------------------------------ 79ms May 29 13:55:00 volumio2 volumio[1026]: info: ------------------------------ 79ms May 29 13:55:00 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:00 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:00 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:01 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:01 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand stop May 29 13:55:01 volumio2 volumio[1026]: info: sendMpdCommand stop took 1 milliseconds May 29 13:55:01 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand clear May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: sendMpdCommand clear took 5 milliseconds May 29 13:55:01 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: error: updateQueue error: null May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 3ms May 29 13:55:01 volumio2 volumio[1026]: info: sendMpdCommand addid "https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube" took 2 milliseconds May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 2ms May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 1ms May 29 13:55:01 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:55:01 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:55:01 volumio2 volumio[1026]: verbose: MPD COMMAND [object Object] May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 18ms May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 7ms May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 7ms May 29 13:55:01 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 13:55:01 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand play May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: May 29 13:55:01 volumio2 volumio[1026]: ---------------------------- MPD announces system playlist update May 29 13:55:01 volumio2 volumio[1026]: info: Ignoring MPD Status Update May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 8ms May 29 13:55:01 volumio2 volumio[1026]: info: sendMpdCommand play took 2 milliseconds May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 3ms May 29 13:55:01 volumio2 volumio[1026]: info: ------------------------------ 2ms May 29 13:55:02 volumio2 mpd[1335]: ffmpeg/opus: Could not update timestamps for skipped samples. May 29 13:55:02 volumio2 volumio[1026]: info: May 29 13:55:02 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:02 volumio2 volumio[1026]: info: May 29 13:55:02 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:02 volumio2 volumio[1026]: info: May 29 13:55:02 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:02 volumio2 volumio[1026]: info: May 29 13:55:02 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand status took 2 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:02 volumio2 volumio[1026]: info: May 29 13:55:02 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:02 volumio2 volumio[1026]: info: May 29 13:55:02 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand status took 19 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand status took 19 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand status took 18 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 18 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand status took 17 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand status took 5 milliseconds May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:02 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:02 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:02 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus stop May 29 13:55:02 volumio2 volumio[1026]: info: ------------------------------ 36ms May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 18 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 12 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 12 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 12 milliseconds May 29 13:55:02 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 11 milliseconds May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:02 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:02 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:02 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:02 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:55:02 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:02 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:02 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:02 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:55:02 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:02 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:02 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:02 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:55:02 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:02 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:02 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:02 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:55:02 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:02 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:02 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:02 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService play May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus play May 29 13:55:02 volumio2 volumio[1026]: info: Received an update from plugin. extracting info from payload May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:02 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:02 volumio2 volumio[1026]: info: ------------------------------ 235ms May 29 13:55:02 volumio2 volumio[1026]: info: ------------------------------ 243ms May 29 13:55:02 volumio2 volumio[1026]: info: ------------------------------ 244ms May 29 13:55:02 volumio2 volumio[1026]: info: ------------------------------ 242ms May 29 13:55:02 volumio2 volumio[1026]: info: ------------------------------ 242ms May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:03 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::pause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::stPlaybackTimer May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::servicePause May 29 13:55:08 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::servicePause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 13:55:08 volumio2 volumio[1026]: info: ControllerMpd::pause May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand pause May 29 13:55:08 volumio2 volumio[1026]: info: May 29 13:55:08 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:08 volumio2 volumio[1026]: info: sendMpdCommand pause took 9 milliseconds May 29 13:55:08 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:08 volumio2 volumio[1026]: info: May 29 13:55:08 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:08 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:08 volumio2 volumio[1026]: info: May 29 13:55:08 volumio2 volumio[1026]: ---------------------------- MPD announces state update: player May 29 13:55:08 volumio2 volumio[1026]: info: ControllerMpd::getState May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand status May 29 13:55:08 volumio2 volumio[1026]: info: sendMpdCommand status took 2 milliseconds May 29 13:55:08 volumio2 volumio[1026]: info: sendMpdCommand status took 2 milliseconds May 29 13:55:08 volumio2 volumio[1026]: info: sendMpdCommand status took 9 milliseconds May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::parseState May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 13:55:08 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 13:55:08 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 13:55:08 volumio2 volumio[1026]: info: sendMpdCommand playlistinfo took 4 milliseconds May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:08 volumio2 volumio[1026]: verbose: ControllerMpd::parseTrackInfo May 29 13:55:08 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:08 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:08 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5509,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:08 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService pause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus pause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::stPlaybackTimer May 29 13:55:08 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:08 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:08 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5509,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:08 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService pause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus pause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::stPlaybackTimer May 29 13:55:08 volumio2 volumio[1026]: info: ControllerMpd::pushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::servicePushState May 29 13:55:08 volumio2 volumio[1026]: info: CorePlayQueue::getTrack 0 May 29 13:55:08 volumio2 volumio[1026]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5509,"duration":2746,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ace of Base - The Sign (1993) [Full Album]","artist":"Ace of Base","album":"YouTube","uri":"https://rr1---sn-8xgn5uxa-4g5z.googlevideo.com/videoplayback?expire=1748544901&ei=JVk4aOXCBvGXhcIPs9Hg-Ac&ip=109.250.158.145&id=o-ALPCupzgPoZEihkIyoIdfR_u9Rbd00_LXErRWL4yH3yQ&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1748523301%2C&mh=tN&mm=31%2C29&mn=sn-8xgn5uxa-4g5z%2Csn-h0jelnez&ms=au%2Crdu&mv=m&mvi=1&pl=22&rms=au%2Cau&initcwndbps=2778750&bui=AY1jyLO7JL5h0LvXlmgs9vhUepuojWhA6M9uN0p29rxwPgPNB4W878Jh6B_uJ7jwQhX0RWEFU8yBKlou&spc=l3OVKZoWeUHPKq-PsRqxYuMaVfOd5VqdZ27Pc7WCrSm3NPk5aGpQj0R2mwmOjEBQn_s&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J8vo2f6WwndLS8S0jMv0R7wQ&rqh=1&gir=yes&clen=44557025&dur=2745.501&lmt=1726805625201658&mt=1748522949&fvip=5&keepalive=yes&fexp=51355912&c=WEB&sefc=1&txp=4532434&n=s_EefUSV2qUgBg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJOOBcz5YWjBXZTFw8jOSEjkZUrPdwYF0ZYf6zIdECx4AiEAkdSTJiLPGiRKPPSDmGnMwM012seS8SjHzwjdbvZWRJ0%3D&sig=AJfQdSswRAIgMir_6rHPZHqd3U2m8tZw6bYmWpDFswSA5Pt0RBNaPA8CIC5DVyqLX-4L7C4797R2o1rhA5R7ivzcVNFC2QYoQA4r&pot=MngbbTtGvSkfYRtR2v3YZJyXnbkhMdW4_uTIn9sJ8xam22Rgw5Y083-4W0pn0efumBi1KodW-X4IILT3h4yxkRL6M5XYv4s8Ra621VXpkEx62SNeTKLUyzRN71l0NKmyqwNdZ3Xr4fRQ4nyFLCRaDAdBzDos2ooqg3k%3D&cver=2.20250222.10.00&t.YouTube","trackType":"YouTube"} May 29 13:55:08 volumio2 volumio[1026]: verbose: CURRENT POSITION 0 May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::syncState stateService pause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::syncState currentStatus pause May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::pushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreCommandRouter::volumioPushState May 29 13:55:08 volumio2 volumio[1026]: info: CoreStateMachine::stPlaybackTimer May 29 13:55:08 volumio2 volumio[1026]: info: ------------------------------ 74ms May 29 13:55:08 volumio2 volumio[1026]: info: ------------------------------ 74ms May 29 13:55:08 volumio2 volumio[1026]: info: ------------------------------ 73ms May 29 13:55:08 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:08 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:08 volumio2 volumio[1026]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 29 13:55:09 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 13:55:21 volumio2 sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 13:55:21 volumio2 sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:55:21 volumio2 sudo[1753]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 13:55:21 volumio2 sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:55:21 volumio2 sudo[1751]: pam_unix(sudo:session): session closed for user root May 29 13:55:21 volumio2 sudo[1753]: pam_unix(sudo:session): session closed for user root May 29 13:55:21 volumio2 volumio[1026]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 May 29 13:55:21 volumio2 sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 13:55:21 volumio2 sudo[1758]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 13:55:21 volumio2 sudo[1758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:55:21 volumio2 sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 13:55:21 volumio2 sudo[1758]: pam_unix(sudo:session): session closed for user root May 29 13:55:21 volumio2 sudo[1759]: pam_unix(sudo:session): session closed for user root May 29 13:55:21 volumio2 volumio[1026]: verbose: New Socket.io Connection to 192.168.178.127 from 192.168.178.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 May 29 13:55:22 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:55:22 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 13:55:22 volumio2 volumio[1026]: info: Listing playlists May 29 13:55:22 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 13:55:22 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 29 13:55:22 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 29 13:55:22 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetQueue May 29 13:55:23 volumio2 volumio[1026]: info: CoreStateMachine::getQueue May 29 13:55:23 volumio2 volumio[1026]: info: CorePlayQueue::getQueue May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 13:55:23 volumio2 volumio[1026]: info: Received Get System Info May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 13:55:23 volumio2 volumio[1026]: info: Discovery: Getting this device information May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:55:23 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 13:55:24 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 13:55:24 volumio2 volumio[1026]: info: Preload queue cleared May 29 13:55:24 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 13:55:24 volumio2 volumio[1026]: info: Received Get System Info May 29 13:55:24 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 13:55:24 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 13:55:24 volumio2 volumio[1026]: info: Discovery: Getting this device information May 29 13:55:24 volumio2 volumio[1026]: info: CoreCommandRouter::volumioGetState May 29 13:55:24 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 13:55:26 volumio2 volumio[1026]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 29 13:55:26 volumio2 volumio[1026]: info: READ May 29 13:55:26 volumio2 volumio[1026]: Use XMLElement.setAttribute instead May 29 13:55:26 volumio2 volumio[1026]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 13:55:26 volumio2 volumio[1026]: TypeError: children[i].attr(...).value is not a function May 29 13:55:26 volumio2 volumio[1026]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 May 29 13:55:26 volumio2 volumio[1026]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 29 13:55:26 volumio2 volumio[1026]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 13:55:28 volumio2 sudo[1790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 13:54' May 29 13:55:28 volumio2 sudo[1790]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 28 19:10:18 UTC 2025" VOLUMIO_VERSION="4.003" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1bd40b20372a0e1ffe6ebc6d3df45570"