May 26 00:48:01 level-up volumio[1354]: error: MyVolumio Plugins failed to start in a timely fashion May 26 00:48:01 level-up volumio[1354]: [Metrics] CommandRouter: 50s 959.34ms May 26 00:48:01 level-up volumio[1354]: info: CoreCommandRouter::volumiosetStartupVolume May 26 00:48:01 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:48:01 level-up volumio[1354]: info: VolumeController:: Setting startup Volume 40 May 26 00:48:01 level-up volumio[1354]: info: VolumeController::SetAlsaVolume40 May 26 00:48:01 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:48:01 level-up volumio[1354]: info: CoreCommandRouter::Close All Modals sent May 26 00:48:01 level-up volumio[1354]: info: CoreCommandRouter::Close All Modals sent May 26 00:48:01 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:48:01 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:01 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:48:01 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:48:01 level-up volumio[1354]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 26 00:48:02 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 26 00:48:02 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 00:48:02 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 26 00:48:08 level-up volumio[1354]: info: BOOT COMPLETED May 26 00:48:41 level-up volumio[1354]: verbose: New Socket.io Connection to 192.168.1.69 from 192.168.1.91 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 26 00:48:41 level-up volumio[1354]: verbose: New Socket.io Connection to 192.168.1.69 from 192.168.1.91 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 26 00:48:45 level-up volumio5-onboarding[1813]: time=2026-05-26T00:48:45.166+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.91:50739 May 26 00:48:46 level-up volumio[1354]: verbose: New Socket.io Connection to 192.168.1.69 from 192.168.1.91 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::volumioGetVisibleSources May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:46 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 00:48:46 level-up volumio[1354]: info: Received Get System Info May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 00:48:46 level-up volumio[1354]: info: Discovery: Getting this device information May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:46 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:46 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:46 level-up volumio[1354]: info: Listing playlists May 26 00:48:46 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 26 00:48:48 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 00:48:48 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 00:48:48 level-up volumio5-onboarding[1813]: time=2026-05-26T00:48:48.122+03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.91:50739 @ 0x1c50d50" latency=86.716877ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 26 00:48:49 level-up sudo[2078]: volumio : unable to resolve host level-up: System error May 26 00:48:49 level-up sudo[2076]: volumio : unable to resolve host level-up: System error May 26 00:48:49 level-up sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 00:48:49 level-up sudo[2076]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 00:48:49 level-up sudo[2076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:48:49 level-up sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:48:49 level-up sudo[2076]: pam_unix(sudo:session): session closed for user root May 26 00:48:49 level-up sudo[2078]: pam_unix(sudo:session): session closed for user root May 26 00:48:50 level-up volumio[1354]: verbose: New Socket.io Connection to 192.168.1.69 from 192.168.1.91 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 26 00:48:50 level-up sudo[2082]: volumio : unable to resolve host level-up: System error May 26 00:48:50 level-up sudo[2082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 26 00:48:50 level-up sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:48:50 level-up sudo[2084]: volumio : unable to resolve host level-up: System error May 26 00:48:50 level-up sudo[2082]: pam_unix(sudo:session): session closed for user root May 26 00:48:50 level-up sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 26 00:48:50 level-up sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:48:50 level-up sudo[2084]: pam_unix(sudo:session): session closed for user root May 26 00:48:51 level-up volumio[1354]: verbose: New Socket.io Connection to 192.168.1.69 from 192.168.1.91 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::volumioGetVisibleSources May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:51 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 00:48:51 level-up volumio[1354]: info: Received Get System Info May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 00:48:51 level-up volumio[1354]: info: Discovery: Getting this device information May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:51 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:51 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:51 level-up volumio[1354]: info: Listing playlists May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 00:48:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 26 00:48:52 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 00:48:52 level-up volumio[1354]: info: Received Get System Info May 26 00:48:52 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 00:48:52 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 00:48:52 level-up volumio[1354]: info: Discovery: Getting this device information May 26 00:48:52 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:52 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:52 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 00:48:52 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 00:48:53 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 26 00:48:54 level-up volumio[1354]: info: CoreCommandRouter::volumioPlay May 26 00:48:54 level-up volumio[1354]: info: CoreStateMachine::play index undefined May 26 00:48:54 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:48:54 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:54 level-up volumio[1354]: info: CoreStateMachine::startPlaybackTimer May 26 00:48:54 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:54 level-up volumio[1354]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D May 26 00:48:55 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 00:48:55 level-up volumio[1354]: info: Received Get System Info May 26 00:48:55 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 00:48:55 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 00:48:55 level-up volumio[1354]: info: Discovery: Getting this device information May 26 00:48:55 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:55 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:55 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 00:48:56 level-up volumio[1354]: info: Received Get System Info May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 00:48:56 level-up volumio[1354]: info: Discovery: Getting this device information May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:56 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 00:48:56 level-up volumio[1354]: info: Received Get System Info May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 00:48:56 level-up volumio[1354]: info: Discovery: Getting this device information May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:48:56 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:48:56 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 00:49:00 level-up volumio[1354]: info: CoreCommandRouter::volumioPlay May 26 00:49:00 level-up volumio[1354]: info: CoreStateMachine::play index undefined May 26 00:49:00 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:49:00 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:00 level-up volumio[1354]: info: CoreStateMachine::startPlaybackTimer May 26 00:49:00 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:00 level-up volumio[1354]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D May 26 00:49:02 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 26 00:49:04 level-up volumio[1354]: info: CoreCommandRouter::volumioPlay May 26 00:49:04 level-up volumio[1354]: info: CoreStateMachine::play index undefined May 26 00:49:04 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:49:04 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:04 level-up volumio[1354]: info: CoreStateMachine::startPlaybackTimer May 26 00:49:04 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:04 level-up volumio[1354]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D May 26 00:49:09 level-up volumio[1354]: info: [ytmusic] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version May 26 00:49:09 level-up volumio[1354]: info: [ytmusic] Innertube support service: Start service with Node May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 00:49:09 level-up volumio[1354]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 26 00:49:09 level-up volumio[1354]: info: Received Get System Version May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 00:49:09 level-up volumio[1354]: info: Received Get System Info May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 00:49:09 level-up volumio[1354]: info: Discovery: Getting this device information May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:49:09 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 00:49:09 level-up volumio[1354]: info: Getting Alsa Cards List without I2S DAC May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 26 00:49:09 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 00:49:10 level-up volumio[1354]: info: [ytmusic] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":33169}} May 26 00:49:10 level-up volumio[1354]: info: [ytmusic] Innertube support service running at http://127.0.0.1:33169 May 26 00:49:12 level-up volumio[1354]: info: [ytmusic] Obtained session PO token using visitorData (expires in 43199 seconds) May 26 00:49:12 level-up volumio[1354]: info: [ytmusic] Going to refresh session PO token in 43099 seconds May 26 00:49:13 level-up volumio[1354]: info: [ytmusic] Obtained PO token for video #vX846ZdnVw8: MltaS0TFnr9LIVoPzTbOX4RfXyINPxwo3NjYO4Ci85TmXJMDAO_wHWXjYTOQOQHxxklWEweTSNbK69jqJNZx3xfdYv-1d2cPCB0VQ4SuHsgHLzjSLl1N2RK5xwjv May 26 00:49:13 level-up volumio[1354]: info: [ytmusic] (Give Way) validating stream URL "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767352&ei=2MMUaouQIOnni9oP38zHwQY&ip=145.224.101.139&id=o-ANScLIrfNkDW3ClyUaw9uon_eZ-r_RDDFTWmRInHPArG&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=382&met=1779745752%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-2of5f5-5p&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwou--GAA-1NJHJVec0iJZ64n5dCyUy168UcGknuKTOiOBAAr5fYv4oQ12MEvVtCzCDjP4PXhaAi&spc=96Xrv_5rViU77b87EOIZLMBgxG_oeXzZ_0mTXAS42FJxjP6SfqTYnbfszTl0NqAYCHs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ILBEIM_OF4-JU0r5CdHEInQV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=lnaZGby8xZIe0g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAI2pSNcrTblRGeg8ZdPt3nuha07wzFj4qyAjgdkk4JR_AiA1Kj1O8SfmpJy8Gc4q5rnCH0VLr5IvoQRes-ZliNe8jg%3D%3D&sig=AHEqNM4wRQIgCxIJ3O5x40vAfkKvgCKjbS92UQBOMef7u_YAPb0zFo0CIQDBP7CiA540_S7IH-jtWsIssIwrmxB0WprNvng67O3d3Q%3D%3D&cver=1.20250219.01.00&pot=MltaS0TFnr9LIVoPzTbOX4RfXyINPxwo3NjYO4Ci85TmXJMDAO_wHWXjYTOQOQHxxklWEweTSNbK69jqJNZx3xfdYv-1d2cPCB0VQ4SuHsgHLzjSLl1N2RK5xwjv"... May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] Obtained PO token for video #vX846ZdnVw8: Mlu-IMeOyAnHlL5kTn2Y6Qjqu0mOdEqeUG08UAPppSJq6Xdog6Smq-lWhVgTcldHSvyyeITYHmBGXjyBp50naZtohpQ2PDG5hKjxKAflSH4Eg8qUbz-ZEV8-AdtB May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (Give Way) validating stream URL "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUat_eDOmL6dsP1cuUmQU&ip=145.224.101.139&id=o-ACY4PL2gid8M3Qgg4QJaiSOCR-I1H8Det6WyC9smXcML&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=449&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-2of5f5-5p&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1560000&bui=AbKmrwooJgZB-GylzQPkx4FEGDTqdOGyBCunP_s_Z9EynBBfG3CDtkAEsAUyqe5T3LHnU2bsTx6EvtB4&spc=96Xrv20o4zoXz1ascbKIEYyeR_pTp3fKcUSQ_flZc7BkWeobRjTSv0ScFuEQMEY0NyM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=eBlVXS_TJrk3WTNhjcZvaK0V&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745520&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=zojL7cRT_CUV7A&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAKLlDuBxbLOWJYd6BFRhSxc3tUV2X72HD0kSStespqesAiEAp98HBITiF6guJJza4_kFKIcEUJAKhJhjZ4rza6TOQUE%3D&sig=AHEqNM4wRgIhANYhNCY2UHSoIc8cVR9a8Issgkn4K7ijAZfZixrPslWyAiEA0bkmO1lLbIGhDgiU8JJlUric3niZNonwwu0_P5JJcww%3D&cver=1.20250219.01.00&pot=Mlu-IMeOyAnHlL5kTn2Y6Qjqu0mOdEqeUG08UAPppSJq6Xdog6Smq-lWhVgTcldHSvyyeITYHmBGXjyBp50naZtohpQ2PDG5hKjxKAflSH4Eg8qUbz-ZEV8-AdtB"... May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] Obtained PO token for video #vX846ZdnVw8: MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (Give Way) validating stream URL "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT"... May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (Give Way) stream validated in 1.096s. May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (AutoplayManager) Added volumioStateListener May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (AutoplayManager) Enabled May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand stop May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand stop took 9 milliseconds May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand clear May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand clear took 0 milliseconds May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand addid "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767352&ei=2MMUaouQIOnni9oP38zHwQY&ip=145.224.101.139&id=o-ANScLIrfNkDW3ClyUaw9uon_eZ-r_RDDFTWmRInHPArG&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=382&met=1779745752%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-2of5f5-5p&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwou--GAA-1NJHJVec0iJZ64n5dCyUy168UcGknuKTOiOBAAr5fYv4oQ12MEvVtCzCDjP4PXhaAi&spc=96Xrv_5rViU77b87EOIZLMBgxG_oeXzZ_0mTXAS42FJxjP6SfqTYnbfszTl0NqAYCHs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ILBEIM_OF4-JU0r5CdHEInQV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=lnaZGby8xZIe0g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAI2pSNcrTblRGeg8ZdPt3nuha07wzFj4qyAjgdkk4JR_AiA1Kj1O8SfmpJy8Gc4q5rnCH0VLr5IvoQRes-ZliNe8jg%3D%3D&sig=AHEqNM4wRQIgCxIJ3O5x40vAfkKvgCKjbS92UQBOMef7u_YAPb0zFo0CIQDBP7CiA540_S7IH-jtWsIssIwrmxB0WprNvng67O3d3Q%3D%3D&cver=1.20250219.01.00&pot=MltaS0TFnr9LIVoPzTbOX4RfXyINPxwo3NjYO4Ci85TmXJMDAO_wHWXjYTOQOQHxxklWEweTSNbK69jqJNZx3xfdYv-1d2cPCB0VQ4SuHsgHLzjSLl1N2RK5xwjv&t.YouTube" May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand addid "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767352&ei=2MMUaouQIOnni9oP38zHwQY&ip=145.224.101.139&id=o-ANScLIrfNkDW3ClyUaw9uon_eZ-r_RDDFTWmRInHPArG&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=382&met=1779745752%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-2of5f5-5p&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwou--GAA-1NJHJVec0iJZ64n5dCyUy168UcGknuKTOiOBAAr5fYv4oQ12MEvVtCzCDjP4PXhaAi&spc=96Xrv_5rViU77b87EOIZLMBgxG_oeXzZ_0mTXAS42FJxjP6SfqTYnbfszTl0NqAYCHs&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ILBEIM_OF4-JU0r5CdHEInQV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=lnaZGby8xZIe0g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAI2pSNcrTblRGeg8ZdPt3nuha07wzFj4qyAjgdkk4JR_AiA1Kj1O8SfmpJy8Gc4q5rnCH0VLr5IvoQRes-ZliNe8jg%3D%3D&sig=AHEqNM4wRQIgCxIJ3O5x40vAfkKvgCKjbS92UQBOMef7u_YAPb0zFo0CIQDBP7CiA540_S7IH-jtWsIssIwrmxB0WprNvng67O3d3Q%3D%3D&cver=1.20250219.01.00&pot=MltaS0TFnr9LIVoPzTbOX4RfXyINPxwo3NjYO4Ci85TmXJMDAO_wHWXjYTOQOQHxxklWEweTSNbK69jqJNZx3xfdYv-1d2cPCB0VQ4SuHsgHLzjSLl1N2RK5xwjv&t.YouTube" took 3 milliseconds May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 3ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 3ms May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 5ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 4ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 2ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 2ms May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand play May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 3ms May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand play took 2 milliseconds May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 1ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 1ms May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (Give Way) stream validated in 0.169s. May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (AutoplayManager) Enabled May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand stop May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand stop took 22 milliseconds May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand clear May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand status took 2 milliseconds May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand clear took 2 milliseconds May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand addid "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUat_eDOmL6dsP1cuUmQU&ip=145.224.101.139&id=o-ACY4PL2gid8M3Qgg4QJaiSOCR-I1H8Det6WyC9smXcML&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=449&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-2of5f5-5p&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1560000&bui=AbKmrwooJgZB-GylzQPkx4FEGDTqdOGyBCunP_s_Z9EynBBfG3CDtkAEsAUyqe5T3LHnU2bsTx6EvtB4&spc=96Xrv20o4zoXz1ascbKIEYyeR_pTp3fKcUSQ_flZc7BkWeobRjTSv0ScFuEQMEY0NyM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=eBlVXS_TJrk3WTNhjcZvaK0V&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745520&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=zojL7cRT_CUV7A&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAKLlDuBxbLOWJYd6BFRhSxc3tUV2X72HD0kSStespqesAiEAp98HBITiF6guJJza4_kFKIcEUJAKhJhjZ4rza6TOQUE%3D&sig=AHEqNM4wRgIhANYhNCY2UHSoIc8cVR9a8Issgkn4K7ijAZfZixrPslWyAiEA0bkmO1lLbIGhDgiU8JJlUric3niZNonwwu0_P5JJcww%3D&cver=1.20250219.01.00&pot=Mlu-IMeOyAnHlL5kTn2Y6Qjqu0mOdEqeUG08UAPppSJq6Xdog6Smq-lWhVgTcldHSvyyeITYHmBGXjyBp50naZtohpQ2PDG5hKjxKAflSH4Eg8qUbz-ZEV8-AdtB&t.YouTube" May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand status took 6 milliseconds May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand status took 6 milliseconds May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 5 milliseconds May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand addid "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUat_eDOmL6dsP1cuUmQU&ip=145.224.101.139&id=o-ACY4PL2gid8M3Qgg4QJaiSOCR-I1H8Det6WyC9smXcML&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=449&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-2of5f5-5p&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1560000&bui=AbKmrwooJgZB-GylzQPkx4FEGDTqdOGyBCunP_s_Z9EynBBfG3CDtkAEsAUyqe5T3LHnU2bsTx6EvtB4&spc=96Xrv20o4zoXz1ascbKIEYyeR_pTp3fKcUSQ_flZc7BkWeobRjTSv0ScFuEQMEY0NyM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=eBlVXS_TJrk3WTNhjcZvaK0V&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745520&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=zojL7cRT_CUV7A&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAKLlDuBxbLOWJYd6BFRhSxc3tUV2X72HD0kSStespqesAiEAp98HBITiF6guJJza4_kFKIcEUJAKhJhjZ4rza6TOQUE%3D&sig=AHEqNM4wRgIhANYhNCY2UHSoIc8cVR9a8Issgkn4K7ijAZfZixrPslWyAiEA0bkmO1lLbIGhDgiU8JJlUric3niZNonwwu0_P5JJcww%3D&cver=1.20250219.01.00&pot=Mlu-IMeOyAnHlL5kTn2Y6Qjqu0mOdEqeUG08UAPppSJq6Xdog6Smq-lWhVgTcldHSvyyeITYHmBGXjyBp50naZtohpQ2PDG5hKjxKAflSH4Eg8qUbz-ZEV8-AdtB&t.YouTube" took 4 milliseconds May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:14 level-up volumio[1354]: 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 26 00:49:14 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState stateService stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: No code May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:14 level-up volumio[1354]: 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 26 00:49:14 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState stateService stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: No code May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.631+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.631+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9620 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.632+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.632+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.632+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9620 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.632+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.633+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.633+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9620 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.633+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.634+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.634+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9620 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.634+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.634+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.634+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9620 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.634+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.635+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.635+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9620 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.635+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 30ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 29ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 31ms May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 26ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 26ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 1ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 1ms May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand play May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:14 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:14 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:14 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:14 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 10ms May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand play took 10 milliseconds May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 10ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 10ms May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (Give Way) stream validated in 0.31s. May 26 00:49:14 level-up volumio[1354]: info: [ytmusic] (AutoplayManager) Enabled May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand stop May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand stop took 158 milliseconds May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand clear May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand clear took 0 milliseconds May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand addid "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube" May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:14 level-up volumio[1354]: error: updateQueue error: null May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand status took 1 milliseconds May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 1ms May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand addid "https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube" took 1 milliseconds May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand status took 1 milliseconds May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:14 level-up volumio[1354]: 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 26 00:49:14 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState stateService stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: No code May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: verbose: MPD COMMAND [object Object] May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:14 level-up volumio[1354]: 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 26 00:49:14 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState stateService stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: No code May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.976+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.976+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.976+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.977+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.977+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.977+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.977+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.977+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.978+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.978+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.978+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.978+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.979+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.979+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.979+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.980+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.980+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.980+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 18ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 18ms May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: sendMpdCommand status took 18 milliseconds May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 18ms May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 0ms May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:14 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand play May 26 00:49:14 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:14 level-up volumio[1354]: 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 26 00:49:14 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState stateService stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus stop May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio[1354]: info: No code May 26 00:49:14 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:14 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.990+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.990+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.990+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.991+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.991+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.991+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.992+03:00 level=WARN msg="received unknown player status" component=volumio status="" May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.992+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_STOPPED positionMs=9870 volume=40 May 26 00:49:14 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:14.992+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id= title= May 26 00:49:14 level-up volumio[1354]: info: ------------------------------ 27ms May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: info: May 26 00:49:14 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:14 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:14 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:14 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:15 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:15 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:15 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:15 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:15 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:15 level-up volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Hai9GXfc0ha2AWgRjWqqGrPc8d83.244e2a784c0d8faf30b479a6060c0082.state.status' May 26 00:49:15 level-up volumio[1354]: info: ------------------------------ 19ms May 26 00:49:15 level-up volumio[1354]: info: sendMpdCommand play took 18 milliseconds May 26 00:49:15 level-up volumio[1354]: info: ------------------------------ 10ms May 26 00:49:15 level-up volumio[1354]: info: ------------------------------ 9ms May 26 00:49:15 level-up volumio[1354]: info: May 26 00:49:15 level-up volumio[1354]: ---------------------------- MPD announces system playlist update May 26 00:49:15 level-up volumio[1354]: info: Ignoring MPD Status Update May 26 00:49:15 level-up volumio[1354]: info: ------------------------------ 1ms May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 10 milliseconds May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 11 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 11 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 0 milliseconds May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService play May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus stop May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 13ms May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 2 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 2 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 2 milliseconds May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService play May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus play May 26 00:49:16 level-up volumio[1354]: info: Received an update from plugin. extracting info from payload May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: [ytmusic] (AutoplayManager) Added mpdStateListener May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService play May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus play May 26 00:49:16 level-up volumio[1354]: info: Received an update from plugin. extracting info from payload May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.096+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.096+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.097+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.097+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.098+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.098+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.102+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.102+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 31ms May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 31ms May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 18 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 18 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 18 milliseconds May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService play May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus play May 26 00:49:16 level-up volumio[1354]: info: Received an update from plugin. extracting info from payload May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.112+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.112+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.112+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.113+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 30ms May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 24 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 24 milliseconds May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"play","position":0,"seek":186,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService play May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus play May 26 00:49:16 level-up volumio[1354]: info: Received an update from plugin. extracting info from payload May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"play","position":0,"seek":186,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService play May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus play May 26 00:49:16 level-up volumio[1354]: info: Received an update from plugin. extracting info from payload May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PLAYING positionMs=0 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.137+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 54ms May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 54ms May 26 00:49:16 level-up volumio[1354]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 26 00:49:16 level-up volumio[1354]: info: Preparing to save Alsa Options, stopping services first May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioGetState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::stPlaybackTimer May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::servicePause May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pause May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand pause May 26 00:49:16 level-up volumio[1354]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"AB13X USB Audio"},"i2s":false,"i2sid":{"value":"max98357a","label":"Adafruit MAX98357"}} May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 00:49:16 level-up volumio[1354]: info: Disabling I2S DAC: May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 00:49:16 level-up volumio[1354]: info: Setting mixer PCM for card AB13X USB Audio May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 26 00:49:16 level-up volumio[1354]: info: Updating Volume Controller Parameters: Device: 5 Name: AB13X USB Audio Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 1 May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 26 00:49:16 level-up volumio[1354]: info: Disabling external Volume Control May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 00:49:16 level-up volumio[1354]: info: Preparing to generate the ALSA configuration file May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand pause took 51 milliseconds May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: May 26 00:49:16 level-up volumio[1354]: ---------------------------- MPD announces state update: player May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::getState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand status May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 9 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 9 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 8 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand status took 8 milliseconds May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseState May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 2 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 2 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 1 milliseconds May 26 00:49:16 level-up volumio[1354]: info: sendMpdCommand playlistinfo took 1 milliseconds May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: verbose: ControllerMpd::parseTrackInfo May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1133,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService pause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus pause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::stPlaybackTimer May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1133,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService pause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus pause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::stPlaybackTimer May 26 00:49:16 level-up volumio[1354]: info: ControllerMpd::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::servicePushState May 26 00:49:16 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:16 level-up volumio[1354]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1133,"duration":276,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Give Way","artist":"Jumanov","album":"Give Way","uri":"https://rr8---sn-x2pm-f5fs.googlevideo.com/videoplayback?expire=1779767353&ei=2cMUatW5Ge2HgMMPkvzg2Qs&ip=145.224.101.139&id=o-AIzU08jGre-uCQ89rofZ14Jm2SkobogZudHiDgBh1CzN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=366&met=1779745753%2C&mh=Iu&mm=31%2C29&mn=sn-x2pm-f5fs%2Csn-f5f7lnl7&ms=au%2Crdu&mv=m&mvi=8&pl=27&rms=au%2Cau&gcr=ua&initcwndbps=1530000&bui=AbKmrwreu5ENXe7CgRfIBk5ZbqeBNRWcSbpo2afQ6DU-_ZykVkdGl7xXYZesX3Fy2FjH0fFck1mFV2bb&spc=96Xrv397q6Ke9RwzRhfKwOlYG9CrIc978v2mGOcqYauwHXEB6azO65DvnQdkZ_Qra8E&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=9zBxGgBSL3_epkBO_li-jZUV&rqh=1&gir=yes&clen=4613086&dur=276.021&lmt=1759476366009662&mt=1779745271&fvip=4&keepalive=yes&fexp=51565116%2C51565682&c=WEB_REMIX&sefc=1&txp=1432534&n=pPkYcIiqi04lIQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgH5EOFsCybxMMLqeMxnRkRG4j-DT_sQp0fCDz6YIpvz4CIQCPY6AqxHlpUHApN3KeUaaJABQFJmdSkp7vfEGcpp9l_w%3D%3D&sig=AHEqNM4wRgIhAM8_RxbV2XHuctda8GQF3Izgo29J6Gw2JZb_5NLS2QtWAiEA-pmSWRwWCqaYYxhpdEOa5WKJXJTcwheDFIkUH1XKkOw%3D&cver=1.20250219.01.00&pot=MlvOBd_sk8fkac5BVh_DJysXy2yWFhFQc5BMdRuL_uxJFAdNm8b9Zcqr9X0LEAyJaQHCXZy6Ra5lo0ykv_98p7iV9rEuXmp3p1WBDR-HE7BOC29XC6sONxwpyJkT&t.YouTube","trackType":"YouTube"} May 26 00:49:16 level-up volumio[1354]: verbose: CURRENT POSITION 0 May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState stateService pause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::syncState currentStatus pause May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::stPlaybackTimer May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.355+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PAUSED positionMs=251 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.355+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.355+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PAUSED positionMs=251 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.355+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.356+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PAUSED positionMs=251 volume=40 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.357+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 21ms May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 20ms May 26 00:49:16 level-up volumio[1354]: info: ------------------------------ 21ms May 26 00:49:16 level-up sudo[2250]: volumio : unable to resolve host level-up: System error May 26 00:49:16 level-up sudo[2250]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 26 00:49:16 level-up sudo[2250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:16 level-up sudo[2250]: pam_unix(sudo:session): session closed for user root May 26 00:49:16 level-up volumio[1354]: sudo: unable to resolve host level-up: System error May 26 00:49:16 level-up volumio[1354]: info: No Overlays Loaded May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 26 00:49:16 level-up volumio[1354]: info: VolumeController:: Volume=56 Mute =false May 26 00:49:16 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:49:16 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.550+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PAUSED positionMs=251 volume=56 May 26 00:49:16 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:16.551+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:16 level-up volumio[1354]: info: Asound.conf file written May 26 00:49:17 level-up sudo[2260]: volumio : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 26 00:49:17 level-up sudo[2260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:17 level-up sudo[2260]: pam_unix(sudo:session): session closed for user root May 26 00:49:17 level-up volumio[1354]: sudo: unable to resolve host level-up: System error May 26 00:49:17 level-up volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 26 00:49:17 level-up volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 26 00:49:17 level-up volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 26 00:49:17 level-up volumio[1354]: info: Output device has changed, restarting MPD May 26 00:49:17 level-up volumio[1354]: info: Output device has changed, restarting Shairport Sync May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 00:49:17 level-up volumio[1354]: info: Output device has changed, restarting MPD May 26 00:49:17 level-up volumio[1354]: info: Output device has changed, restarting Shairport Sync May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 00:49:17 level-up volumio[1354]: info: Starting Shairport Sync May 26 00:49:17 level-up volumio[1354]: info: Starting Shairport Sync May 26 00:49:17 level-up sudo[2266]: volumio : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2274]: volumio : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 00:49:17 level-up sudo[2274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 00:49:17 level-up sudo[2266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:17 level-up sudo[2274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:17 level-up sudo[2269]: volumio : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2274]: pam_unix(sudo:session): session closed for user root May 26 00:49:17 level-up sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 00:49:17 level-up sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:17 level-up sudo[2266]: pam_unix(sudo:session): session closed for user root May 26 00:49:17 level-up volumio[1354]: info: MPD Permissions set May 26 00:49:17 level-up volumio[1354]: info: MPD Permissions set May 26 00:49:17 level-up sudo[2276]: volumio : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 00:49:17 level-up sudo[2276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:17 level-up systemd[1]: Stopping mpd.service - Music Player Daemon... May 26 00:49:17 level-up sudo[2296]: volumio : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2294]: volumio : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 00:49:17 level-up sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 00:49:17 level-up sudo[2296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:17 level-up sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 00:49:17 level-up systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 00:49:17 level-up systemd[1]: shairport-sync.service: Deactivated successfully. May 26 00:49:17 level-up systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 00:49:17 level-up systemd[1]: shairport-sync.service: Consumed 1.621s CPU time. May 26 00:49:17 level-up systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 00:49:17 level-up sudo[2294]: pam_unix(sudo:session): session closed for user root May 26 00:49:17 level-up sudo[2296]: pam_unix(sudo:session): session closed for user root May 26 00:49:17 level-up volumio[1354]: info: Shairport-Sync Started May 26 00:49:17 level-up volumio[1354]: info: Shairport-Sync Started May 26 00:49:17 level-up systemd[1]: mpd.service: Deactivated successfully. May 26 00:49:17 level-up systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 00:49:17 level-up systemd[1]: mpd.service: Consumed 1.616s CPU time. May 26 00:49:17 level-up systemd[1]: mpd.socket: Deactivated successfully. May 26 00:49:17 level-up systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 00:49:17 level-up systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 00:49:17 level-up systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 00:49:17 level-up systemd[1]: Starting mpd.service - Music Player Daemon... May 26 00:49:17 level-up sudo[2319]: root : unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2319]: sudo: unable to resolve host level-up: System error May 26 00:49:17 level-up sudo[2319]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 00:49:17 level-up sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 00:49:17 level-up sudo[2319]: pam_unix(sudo:session): session closed for user root May 26 00:49:18 level-up mpd[2321]: 2026-05-26T00:49:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 00:49:18 level-up systemd[1]: Started mpd.service - Music Player Daemon. May 26 00:49:18 level-up sudo[2276]: pam_unix(sudo:session): session closed for user root May 26 00:49:18 level-up sudo[2269]: pam_unix(sudo:session): session closed for user root May 26 00:49:18 level-up volumio[1354]: error: MPD error: The expression evaluated to a falsy value: May 26 00:49:18 level-up volumio[1354]: assert.ok(self.idling) May 26 00:49:18 level-up volumio[1354]: error: The expression evaluated to a falsy value: May 26 00:49:18 level-up volumio[1354]: assert.ok(self.idling) May 26 00:49:18 level-up volumio[1354]: error: updateQueue error: null May 26 00:49:18 level-up volumio[1354]: info: CoreCommandRouter::volumioPlay May 26 00:49:18 level-up volumio[1354]: info: CoreStateMachine::play index undefined May 26 00:49:18 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:49:18 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:18 level-up volumio[1354]: info: CoreStateMachine::startPlaybackTimer May 26 00:49:18 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:18 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:18 level-up volumio[1354]: info: ControllerMpd::resume May 26 00:49:18 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand play May 26 00:49:18 level-up volumio[1354]: info: sendMpdCommand play took 0 milliseconds May 26 00:49:20 level-up volumio[1354]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 26 00:49:20 level-up volumio[1354]: info: CoreStateMachine::getcurrentVolume May 26 00:49:20 level-up volumio[1354]: info: CoreCommandRouter::volumioRetrievevolume May 26 00:49:20 level-up volumio[1354]: info: VolumeController:: Volume=56 Mute =false May 26 00:49:20 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:20 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 00:49:20 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:20 level-up volumio[1354]: info: CoreStateMachine::updateTrackBlock May 26 00:49:20 level-up volumio[1354]: info: CorePlayQueue::getTrackBlock May 26 00:49:20 level-up volumio[1354]: info: CoreCommandRouter::volumioRetrievevolume May 26 00:49:20 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:20.355+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PAUSED positionMs=2342 volume=56 May 26 00:49:20 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:20.355+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:20 level-up volumio[1354]: info: VolumeController:: Volume=56 Mute =false May 26 00:49:20 level-up volumio[1354]: info: CoreStateMachine::pushState May 26 00:49:20 level-up volumio[1354]: info: CoreCommandRouter::volumioPushState May 26 00:49:20 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:20.371+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" state=STATUS_PAUSED positionMs=2342 volume=56 May 26 00:49:20 level-up volumio5-onboarding[1813]: time=2026-05-26T00:49:20.371+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.91:50739 @ 0x1c50d50" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Give%20Way%22%2C%22artist%22%3A%22Jumanov%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2FjBaP1AQU7KxnUWmm6A2U9Ev8q-jUCF6a3GwRcFSOGbAlmUtcFaFbw7Dht0qpPOKtUWFxtdvpOsDloh8%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22vX846ZdnVw8%22%2C%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22index%22%3A0%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_kI7Ryze0e625NXNrb_pcSevwwlWrS6OsM%22%2C%22videoId%22%3A%22CbDKn_vs2tg%22%7D%7D%7D%7D" title="Give Way" May 26 00:49:21 level-up volumio[1354]: info: CoreCommandRouter::volumioPlay May 26 00:49:21 level-up volumio[1354]: info: CoreStateMachine::play index undefined May 26 00:49:21 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:49:21 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:21 level-up volumio[1354]: info: CoreStateMachine::startPlaybackTimer May 26 00:49:21 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:21 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:21 level-up volumio[1354]: info: ControllerMpd::resume May 26 00:49:21 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand play May 26 00:49:21 level-up volumio[1354]: info: sendMpdCommand play took 1 milliseconds May 26 00:49:29 level-up volumio[1354]: info: CoreCommandRouter::volumioPlay May 26 00:49:29 level-up volumio[1354]: info: CoreStateMachine::play index undefined May 26 00:49:29 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:49:29 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:29 level-up volumio[1354]: info: CoreStateMachine::startPlaybackTimer May 26 00:49:29 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:29 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:29 level-up volumio[1354]: info: ControllerMpd::resume May 26 00:49:29 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand play May 26 00:49:29 level-up volumio[1354]: info: sendMpdCommand play took 1 milliseconds May 26 00:49:31 level-up volumio[1354]: info: CoreCommandRouter::volumioPlay May 26 00:49:31 level-up volumio[1354]: info: CoreStateMachine::play index undefined May 26 00:49:31 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 00:49:31 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:31 level-up volumio[1354]: info: CoreStateMachine::startPlaybackTimer May 26 00:49:31 level-up volumio[1354]: info: CorePlayQueue::getTrack 0 May 26 00:49:31 level-up volumio[1354]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 00:49:31 level-up volumio[1354]: info: ControllerMpd::resume May 26 00:49:31 level-up volumio[1354]: verbose: ControllerMpd::sendMpdCommand play May 26 00:49:31 level-up volumio[1354]: info: sendMpdCommand play took 0 milliseconds May 26 00:49:35 level-up bluealsa[1116]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_51_7C_A9_A5_E4_CA, ...) May 26 00:49:37 level-up volumio[1354]: info: CoreCommandRouter::volumioGetQueue May 26 00:49:37 level-up volumio[1354]: info: CoreStateMachine::getQueue May 26 00:49:37 level-up volumio[1354]: info: CorePlayQueue::getQueue May 26 00:49:40 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri May 26 00:49:40 level-up volumio[1354]: info: [ytmusic-browse] browseUri: ytmusic May 26 00:49:40 level-up volumio[1354]: info: Preload queue cleared May 26 00:49:51 level-up volumio[1354]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch May 26 00:49:51 level-up volumio[1354]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 00:49:51 level-up volumio[1354]: TypeError: Cannot read properties of undefined (reading 'then') May 26 00:49:51 level-up volumio[1354]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) May 26 00:49:51 level-up volumio[1354]: at Socket.emit (node:events:514:28) May 26 00:49:51 level-up volumio[1354]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 26 00:49:51 level-up volumio[1354]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 26 00:49:51 level-up volumio[1354]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 00:49:52 level-up sudo[2390]: volumio : unable to resolve host level-up: System error May 26 00:49:52 level-up sudo[2390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 00:48' May 26 00:49:52 level-up sudo[2390]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"