Jun 04 18:18:02 volumio-2 volumio[1044]: info: Setting Geolocation for MyVolumio to eu9 Jun 04 18:18:02 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:02 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:02 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: Updating MyVolumio device info Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 18:18:03 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 18:18:04 volumio-2 sudo[1607]: pam_unix(sudo:session): session closed for user root Jun 04 18:18:04 volumio-2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 04 18:18:04 volumio-2 kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jun 04 18:18:04 volumio-2 volumio[1044]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Jun 04 18:18:04 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:18:04 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:18:04 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:18:04 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:04 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 04 18:18:04 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:18:04 volumio-2 volumio[1044]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 04 18:18:05 volumio-2 volumio[1044]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 04 18:18:05 volumio-2 volumio[1044]: info: Completed starting MyVolumio Plugin Jun 04 18:18:05 volumio-2 volumio[1044]: [Metrics] CommandRouter: 69s 998.13ms Jun 04 18:18:05 volumio-2 volumio[1044]: info: CoreCommandRouter::volumiosetStartupVolume Jun 04 18:18:05 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 18:18:05 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:05 volumio-2 volumio[1044]: info: CoreCommandRouter::Close All Modals sent Jun 04 18:18:05 volumio-2 volumio[1044]: info: CoreCommandRouter::Close All Modals sent Jun 04 18:18:05 volumio-2 volumio[1044]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 04 18:18:06 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 04 18:18:06 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 04 18:18:06 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 04 18:18:06 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 18:18:07 volumio-2 volumio[1044]: info: Received Get System Info Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:18:07 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:07 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 18:18:07 volumio-2 volumio[1044]: info: Received Get System Info Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:18:07 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:07 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 04 18:18:07 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:08 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 0 Jun 04 18:18:08 volumio-2 volumio[1044]: info: MYVOLUMIO: Adding device Jun 04 18:18:08 volumio-2 volumio[1044]: info: MYVOLUMIO: Evaluating Server Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreStateMachine::play index 2 Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreStateMachine::stop Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:08 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:08 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:18:08 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:08 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:18:08 volumio-2 volumio[1044]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Jun 04 18:18:09 volumio-2 sudo[1668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=carmel,password=alinco,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.88.148/Public/music /mnt/NAS/NAS Jun 04 18:18:09 volumio-2 sudo[1668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 18:18:09 volumio-2 kernel: CIFS: Attempting to mount //192.168.88.148/Public/music Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreStateMachine::play index 2 Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreStateMachine::stop Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:10 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:18:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:10 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:18:12 volumio-2 volumio[1044]: info: BOOT COMPLETED Jun 04 18:18:12 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 18:18:12 volumio-2 volumio[1044]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 04 18:18:12 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 04 18:18:12 volumio-2 volumio[1044]: info: Received Get System Version Jun 04 18:18:12 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 18:18:12 volumio-2 volumio[1044]: info: Received Get System Info Jun 04 18:18:12 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:18:12 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:18:12 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:18:12 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:12 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:12 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:18:15 volumio-2 volumio[1044]: info: Setting Geolocation for MyVolumio to eu6 Jun 04 18:18:15 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:15 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:15 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:16 volumio-2 volumio[1044]: info: Updating MyVolumio device info Jun 04 18:18:16 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:16 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:16 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 18:18:17 volumio-2 volumio[1044]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 04 18:18:18 volumio-2 volumio[1044]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 04 18:18:18 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:18 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:18 volumio-2 volumio[1044]: info: Listing playlists Jun 04 18:18:18 volumio-2 volumio[1044]: info: Listing playlists Jun 04 18:18:19 volumio-2 sudo[1668]: pam_unix(sudo:session): session closed for user root Jun 04 18:18:19 volumio-2 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 04 18:18:19 volumio-2 kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jun 04 18:18:19 volumio-2 volumio[1044]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 04 18:18:23 volumio-2 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 04 18:18:37 volumio-2 volumio[1044]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... Jun 04 18:18:39 volumio-2 volumio[1044]: info: VolumeController::SetAlsaVolume25 Jun 04 18:18:39 volumio-2 volumio[1044]: info: VolumeController::SetAlsaVolume15 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::play index 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::play index 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::play index 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::stop Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::stop Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::stop Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:18:39 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:18:39 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:39 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:18:39 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:18:40 volumio-2 volumio[1044]: info: Listing playlists Jun 04 18:18:40 volumio-2 volumio[1044]: info: Listing playlists Jun 04 18:18:44 volumio-2 volumio[1044]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Jun 04 18:18:44 volumio-2 volumio[1044]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Jun 04 18:18:44 volumio-2 volumio[1044]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Jun 04 18:18:48 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:18:48 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:04 volumio-2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 04 18:19:04 volumio-2 dbus-daemon[492]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.24' (uid=0 pid=1762 comm="timedatectl show --property=NTPSynchronized --valu") Jun 04 18:19:04 volumio-2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 04 18:19:04 volumio-2 dbus-daemon[492]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 04 18:19:04 volumio-2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 04 18:19:04 volumio-2 setdatetime-helper.sh[1761]: Time is not synchronized. Attempting to sync... Jun 04 18:19:05 volumio-2 sudo[1770]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 04 Jun 2025 16:19:06 GMT#015' Jun 04 18:19:05 volumio-2 sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 04 18:19:06 volumio-2 setdatetime-helper.sh[1771]: Wed Jun 4 18:19:06 CEST 2025 Jun 04 18:19:06 volumio-2 sudo[1770]: pam_unix(sudo:session): session closed for user root Jun 04 18:19:06 volumio-2 setdatetime-helper.sh[1761]: Time synchronized successfully. Jun 04 18:19:06 volumio-2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 04 18:19:06 volumio-2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 04 18:19:09 volumio-2 volumio[1044]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPlay Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::play index undefined Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreStateMachine::startPlaybackTimer Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Tina%20Turner%20-%20Live%20Greatest%20Hits%20Full%20Album%20-%20Best%20Songs%20Playlist%22%2C%22artist%22%3A%22MindPaths%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FfdR41tQBulQ%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3kJYgUC0tAt4NZtrKx7JlAISg0OaQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22fdR41tQBulQ%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:19:10 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 18:19:10 volumio-2 volumio[1044]: info: Discovery: Getting this device information Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:19:10 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:10 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 18:19:10 volumio-2 volumio[1044]: info: Listing playlists Jun 04 18:19:10 volumio-2 volumio[1044]: info: Listing playlists Jun 04 18:19:12 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:12 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 18:19:12 volumio-2 volumio[1044]: info: sendMpdCommand stop took 137 milliseconds Jun 04 18:19:12 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: sendMpdCommand clear took 14 milliseconds Jun 04 18:19:12 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075550&ei=_nFAaPz7KqSczPsPmufFGQ&ip=85.232.208.2&id=o-ALFCsviQeczxy9MUe3b0AwmhkI-7F_DXcfGRogskGXUV&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053950%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLMe5sg0pC4kiqByfIjjhlGrijepi7Qa0QbdyH9I9hAS6c76i5Ya_3t65_h_WuJTky6I3CTYQ4FI&spc=l3OVKf2gvAnqTpmh21ceEcSU6jujluK2O6ZckkC2z7987gaR9Ong9QdO5Q3s5GkJ5F4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=MeLjtqaG4ORU434Skclyx_YQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=nug8JR45q6-Baw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgTkBCdFiBSwsBa9MhI7FnY1BiA9qw5-0Ic8YC4YCMuY4CIBEK5Bq7IH2hzL4uKuz2Wnm3DmRC6sOpaizo_xMQ12tF&sig=AJfQdSswRAIgP4CulNh01DjyjuHr-6EPasCsrkZsXPEKRMpzlms0kAYCIAU-747CkkBqWvEkCQqv2ttfj_aMThCV4NdJQmakuYAt&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: error: updateQueue error: null Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 55ms Jun 04 18:19:12 volumio-2 volumio[1044]: info: sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075550&ei=_nFAaPz7KqSczPsPmufFGQ&ip=85.232.208.2&id=o-ALFCsviQeczxy9MUe3b0AwmhkI-7F_DXcfGRogskGXUV&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053950%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLMe5sg0pC4kiqByfIjjhlGrijepi7Qa0QbdyH9I9hAS6c76i5Ya_3t65_h_WuJTky6I3CTYQ4FI&spc=l3OVKf2gvAnqTpmh21ceEcSU6jujluK2O6ZckkC2z7987gaR9Ong9QdO5Q3s5GkJ5F4&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=MeLjtqaG4ORU434Skclyx_YQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=nug8JR45q6-Baw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgTkBCdFiBSwsBa9MhI7FnY1BiA9qw5-0Ic8YC4YCMuY4CIBEK5Bq7IH2hzL4uKuz2Wnm3DmRC6sOpaizo_xMQ12tF&sig=AJfQdSswRAIgP4CulNh01DjyjuHr-6EPasCsrkZsXPEKRMpzlms0kAYCIAU-747CkkBqWvEkCQqv2ttfj_aMThCV4NdJQmakuYAt&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" took 39 milliseconds Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 40ms Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 36ms Jun 04 18:19:12 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:12 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 49ms Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 28ms Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 22ms Jun 04 18:19:12 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 18:19:12 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Jun 04 18:19:12 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:12 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 39ms Jun 04 18:19:12 volumio-2 volumio[1044]: info: sendMpdCommand play took 28 milliseconds Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 30ms Jun 04 18:19:12 volumio-2 volumio[1044]: info: ------------------------------ 22ms Jun 04 18:19:13 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand stop took 279 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand status took 24 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand clear took 15 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075551&ei=_3FAaOPqF77Si9oPp7C0yAY&ip=85.232.208.2&id=o-AH5ZObwq9hlYJKitzZcyf2UYOX9qbplzLlY2OlKB65Kj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053951%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLMM3x6VC-m-v-yJ5B8_5BLrd3MAWNBdmLWoQj5N90RBXt3lLCwLcSeAY_RXQK4BQeOWuT8CTFUM&spc=l3OVKV0maWkfbNvIcmk0W-a_M4MSIA7WnrS-ngaMw7xdCoe3Py6YBZh8F1xsoV3lcyc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6yid9wbc-BrmI8r5TRI5noAQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=Qux_lVgccW852g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhALESYyQV_8jjQ58oWf5W_82EHvdLzc301zxxlu7p30xfAiBkb1rriLpq0IFAt-Z3r3HrynsV6e0qr2e4FlOaxR932g%3D%3D&sig=AJfQdSswRQIhAI0GbYs2YkypyPte320RJf7DrMYiqLM-chKDV0jpH3s0AiBi8gHEJzc0tW9EaPZQxFyOBYDoDHFjxVdSpTXXODxnpA%3D%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:14 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:14 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:14 volumio-2 volumio[1044]: error: updateQueue error: null Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 32ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075551&ei=_3FAaOPqF77Si9oPp7C0yAY&ip=85.232.208.2&id=o-AH5ZObwq9hlYJKitzZcyf2UYOX9qbplzLlY2OlKB65Kj&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053951%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLMM3x6VC-m-v-yJ5B8_5BLrd3MAWNBdmLWoQj5N90RBXt3lLCwLcSeAY_RXQK4BQeOWuT8CTFUM&spc=l3OVKV0maWkfbNvIcmk0W-a_M4MSIA7WnrS-ngaMw7xdCoe3Py6YBZh8F1xsoV3lcyc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6yid9wbc-BrmI8r5TRI5noAQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=Qux_lVgccW852g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhALESYyQV_8jjQ58oWf5W_82EHvdLzc301zxxlu7p30xfAiBkb1rriLpq0IFAt-Z3r3HrynsV6e0qr2e4FlOaxR932g%3D%3D&sig=AJfQdSswRQIhAI0GbYs2YkypyPte320RJf7DrMYiqLM-chKDV0jpH3s0AiBi8gHEJzc0tW9EaPZQxFyOBYDoDHFjxVdSpTXXODxnpA%3D%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" took 22 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand status took 25 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand status took 23 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:14 volumio-2 volumio[1044]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:14 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:14 volumio-2 volumio[1044]: 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} Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: No code Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:14 volumio-2 volumio[1044]: 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} Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: No code Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 210ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 338ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 337ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 367ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 40ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 39ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 18:19:14 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Jun 04 18:19:14 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:14 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 152ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: sendMpdCommand play took 143 milliseconds Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 142ms Jun 04 18:19:14 volumio-2 volumio[1044]: info: ------------------------------ 124ms Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand stop took 38 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:16 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:16 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand status took 21 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand clear took 20 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand status took 19 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand status took 8 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075554&ei=AnJAaJ74FKKhi9oPmePD4Qw&ip=85.232.208.2&id=o-ADbyed5PAQXtlcBERXO3_qyp0_NAaMGVLBqG_m62OtRR&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053954%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7kn7d&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLOr7qvLg6hkK1dLIb2cfgqptoevX0LYJLCBzRu8FWDBgbVfctXlv6zIJF0NIEYC0KgkY3sV9pqg&spc=l3OVKTzl7TR0uFuFG5Zl_gL1cP8vXEgKhgt8a14ZZ-XGd6HakZKU67aaJ4tS4pCnsEM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J6uqGoLhF4eHYrC0H42ikgMQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=WAIKmboNzOuXCg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgI6zLMLMLkA_jU2prLS8TDKfREQS7deuQUFeIvGTE9roCIDRPKLhb94g8DUHbtHkH7vv6wcDgOaVONQhqxuLuaCHs&sig=AJfQdSswRgIhAK7TkJwW4eM8-jSpk-hdi7Mlcfbt9f_h-IB4IV4y0kUJAiEA1ngb6rM-XGTBTtp8IKFoZQsOO5btJLzTkglcQB7ZhI8%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:16 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:16 volumio-2 volumio[1044]: 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} Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: No code Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:16 volumio-2 volumio[1044]: 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} Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: No code Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 238ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 236ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: error: updateQueue error: null Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 285ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand playlistinfo took 277 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075554&ei=AnJAaJ74FKKhi9oPmePD4Qw&ip=85.232.208.2&id=o-ADbyed5PAQXtlcBERXO3_qyp0_NAaMGVLBqG_m62OtRR&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053954%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7kn7d&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLOr7qvLg6hkK1dLIb2cfgqptoevX0LYJLCBzRu8FWDBgbVfctXlv6zIJF0NIEYC0KgkY3sV9pqg&spc=l3OVKTzl7TR0uFuFG5Zl_gL1cP8vXEgKhgt8a14ZZ-XGd6HakZKU67aaJ4tS4pCnsEM&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=J6uqGoLhF4eHYrC0H42ikgMQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=WAIKmboNzOuXCg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgI6zLMLMLkA_jU2prLS8TDKfREQS7deuQUFeIvGTE9roCIDRPKLhb94g8DUHbtHkH7vv6wcDgOaVONQhqxuLuaCHs&sig=AJfQdSswRgIhAK7TkJwW4eM8-jSpk-hdi7Mlcfbt9f_h-IB4IV4y0kUJAiEA1ngb6rM-XGTBTtp8IKFoZQsOO5btJLzTkglcQB7ZhI8%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" took 278 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 54ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 51ms Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 04 18:19:16 volumio-2 volumio[1044]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 316ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 149ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 131ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 131ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 18:19:16 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Jun 04 18:19:16 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:16 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 41ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: sendMpdCommand play took 24 milliseconds Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 30ms Jun 04 18:19:16 volumio-2 volumio[1044]: info: ------------------------------ 14ms Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 18:19:18 volumio-2 volumio[1044]: info: Jun 04 18:19:18 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:18 volumio-2 volumio[1044]: info: sendMpdCommand stop took 100 milliseconds Jun 04 18:19:18 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 18:19:18 volumio-2 volumio[1044]: info: Jun 04 18:19:18 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:18 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:18 volumio-2 volumio[1044]: info: Jun 04 18:19:18 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:18 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:18 volumio-2 volumio[1044]: info: Jun 04 18:19:18 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:18 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:18 volumio-2 volumio[1044]: info: sendMpdCommand status took 33 milliseconds Jun 04 18:19:18 volumio-2 volumio[1044]: info: sendMpdCommand clear took 35 milliseconds Jun 04 18:19:18 volumio-2 volumio[1044]: info: sendMpdCommand status took 24 milliseconds Jun 04 18:19:18 volumio-2 volumio[1044]: info: sendMpdCommand status took 24 milliseconds Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075557&ei=BXJAaKeDEqaizPsPwOvi-Q4&ip=85.232.208.2&id=o-AM8XJmnz3Req0sPNjZUvAfeaIhumOuXmykzaSWzaH04Q&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053957%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7kn7d&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLOO2FnlWS3X-R8WAFL5lRCbhAMnMrXf4D9dWx9lMfCX3a7R1PIUI3JV9vQjmHnq1-DRHW9Q_Z8c&spc=l3OVKYF0lJEfgQFZG43J-tfptFTLX9h1678wWZm1gzkwylne-9WW8778qSoZwzCTeas&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=qfel3G2V1zfdsBZhvpf56fkQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=QaRs5-Cifbw-1g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ6EGxZTObqbLIHOJdC5IbyogCT_Yo0_aRDF-FR760DkAiEA2I4LaNxA2yQiTWYKTaf9FYjkhdvG8sFI6upS_B9rSwk%3D&sig=AJfQdSswRAIgJnUrBpwck2uXEik9SDYrucZ4Xpaz9T9jHoFh07nL-kUCIEKvD3SIsCGeHU4CYBtZBlU6bIBAmVT2Hq3cJIBStoGX&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:18 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:18 volumio-2 volumio[1044]: 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} Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: No code Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:18 volumio-2 volumio[1044]: 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} Jun 04 18:19:18 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: No code Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:18 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 274ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 279ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: error: updateQueue error: null Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 279ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand playlistinfo took 282 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075557&ei=BXJAaKeDEqaizPsPwOvi-Q4&ip=85.232.208.2&id=o-AM8XJmnz3Req0sPNjZUvAfeaIhumOuXmykzaSWzaH04Q&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053957%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7kn7d&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLOO2FnlWS3X-R8WAFL5lRCbhAMnMrXf4D9dWx9lMfCX3a7R1PIUI3JV9vQjmHnq1-DRHW9Q_Z8c&spc=l3OVKYF0lJEfgQFZG43J-tfptFTLX9h1678wWZm1gzkwylne-9WW8778qSoZwzCTeas&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=qfel3G2V1zfdsBZhvpf56fkQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=QaRs5-Cifbw-1g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJ6EGxZTObqbLIHOJdC5IbyogCT_Yo0_aRDF-FR760DkAiEA2I4LaNxA2yQiTWYKTaf9FYjkhdvG8sFI6upS_B9rSwk%3D&sig=AJfQdSswRAIgJnUrBpwck2uXEik9SDYrucZ4Xpaz9T9jHoFh07nL-kUCIEKvD3SIsCGeHU4CYBtZBlU6bIBAmVT2Hq3cJIBStoGX&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" took 283 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 28ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 27ms Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 04 18:19:19 volumio-2 volumio[1044]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 323ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 97ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 85ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 72ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 14ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand play took 13 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 8ms Jun 04 18:19:19 volumio-2 volumio[1044]: info: ------------------------------ 7ms Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand stop took 25 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:19 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:19 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:19 volumio-2 volumio[1044]: info: Jun 04 18:19:19 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:19 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand status took 20 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand clear took 20 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand status took 19 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: info: sendMpdCommand status took 19 milliseconds Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075554&ei=AnJAaK_vHKuIsvQP7NDDyQM&ip=85.232.208.2&id=o-APZvTs89Rp4axhUJYqpBiX9mpa58G4EBwYM-1mU93SUS&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053954%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLOc9zjgf8Ej21gjKg_ks75iUh8pJKMK7KOeop3BRX8rWgAtqcIwDCJYVaT4VemtMutxPbt1Yc0A&spc=l3OVKfssICdcrr93BZWCT2caNgyghlaVhpkePsKGRG32TigAJOQ5PArMfFEMv-vwQls&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kxWuslV_Qp7XfwSIkhCpQ38Q&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=E2Me_T804u5LWg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJHunHtg-7i5STdhnh4o20BwV_T27p50WttFjsazptLFAiEA7jZ9XEd8qcy1_1c7nM9SW9HZFUu--zS-mLtusg7pkOs%3D&sig=AJfQdSswRgIhAIDbxuo5F1CUe4D65nujUxAB1eMy4mpoBafvKTb3FGWIAiEAxokigiFsxHbl9GTt6dyxSYjcA3F6SG9_r8s1uVdPf7k%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:19 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:19 volumio-2 volumio[1044]: 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} Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: No code Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:19 volumio-2 volumio[1044]: 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} Jun 04 18:19:19 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: No code Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:19 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 275ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 280ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: error: updateQueue error: null Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 306ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: sendMpdCommand playlistinfo took 304 milliseconds Jun 04 18:19:20 volumio-2 volumio[1044]: info: sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075554&ei=AnJAaK_vHKuIsvQP7NDDyQM&ip=85.232.208.2&id=o-APZvTs89Rp4axhUJYqpBiX9mpa58G4EBwYM-1mU93SUS&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053954%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLOc9zjgf8Ej21gjKg_ks75iUh8pJKMK7KOeop3BRX8rWgAtqcIwDCJYVaT4VemtMutxPbt1Yc0A&spc=l3OVKfssICdcrr93BZWCT2caNgyghlaVhpkePsKGRG32TigAJOQ5PArMfFEMv-vwQls&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kxWuslV_Qp7XfwSIkhCpQ38Q&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=E2Me_T804u5LWg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJHunHtg-7i5STdhnh4o20BwV_T27p50WttFjsazptLFAiEA7jZ9XEd8qcy1_1c7nM9SW9HZFUu--zS-mLtusg7pkOs%3D&sig=AJfQdSswRgIhAIDbxuo5F1CUe4D65nujUxAB1eMy4mpoBafvKTb3FGWIAiEAxokigiFsxHbl9GTt6dyxSYjcA3F6SG9_r8s1uVdPf7k%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" took 307 milliseconds Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 47ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 46ms Jun 04 18:19:20 volumio-2 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 04 18:19:20 volumio-2 volumio[1044]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 04 18:19:20 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:20 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 333ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 186ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 179ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 164ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 18:19:20 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Jun 04 18:19:20 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:20 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 96ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: sendMpdCommand play took 89 milliseconds Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 89ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: ------------------------------ 80ms Jun 04 18:19:20 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioGetState Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 18:19:21 volumio-2 volumio[1044]: info: Jun 04 18:19:21 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:21 volumio-2 volumio[1044]: info: sendMpdCommand stop took 603 milliseconds Jun 04 18:19:21 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:21 volumio-2 volumio[1044]: info: sendMpdCommand stop took 36 milliseconds Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 18:19:21 volumio-2 volumio[1044]: info: Jun 04 18:19:21 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:21 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:21 volumio-2 volumio[1044]: info: Jun 04 18:19:21 volumio-2 volumio[1044]: ---------------------------- MPD announces state update: player Jun 04 18:19:21 volumio-2 volumio[1044]: info: ControllerMpd::getState Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Jun 04 18:19:21 volumio-2 volumio[1044]: info: Jun 04 18:19:21 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:21 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:21 volumio-2 volumio[1044]: info: Jun 04 18:19:21 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:21 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:21 volumio-2 volumio[1044]: info: sendMpdCommand status took 21 milliseconds Jun 04 18:19:21 volumio-2 volumio[1044]: info: sendMpdCommand clear took 19 milliseconds Jun 04 18:19:21 volumio-2 volumio[1044]: info: sendMpdCommand clear took 11 milliseconds Jun 04 18:19:21 volumio-2 volumio[1044]: info: sendMpdCommand status took 5 milliseconds Jun 04 18:19:21 volumio-2 volumio[1044]: info: sendMpdCommand status took 3 milliseconds Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075558&ei=BnJAaJryF6bVi9oPgvqc-Qg&ip=85.232.208.2&id=o-APBiFqLqJyS4-qo4vdIEud4wHbsDs9psA0CyoCGbpBuE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053958%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLNfzmQ4ReagdMkZdM_JL7lSwYfFL7jhAp_GMU4PrHwKPHBoFz-erwaSoidHHiCwlDxErDFNfz1L&spc=l3OVKdXEo5VDvgzjfgxanduInqO4pgccu9qdVnv-CvINbenpT8sVvg3jZ4vN-a8jo4o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=l6Hn3D6K64TtBvkA1wGiNFwQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=pG_WT2T-uZxUmQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgH-Uw9V9x1OZAU4WACXAh1ELWSzcQc6hEYA2ZyUVq_lcCIBz-qezEF6uwcX78wA3efCxrxk30JJKbjbGEJ5QErCvx&sig=AJfQdSswRAIgKFcIen-37ePPyzMzDT-DElFKd3waRmt9UeCcPGUGFFwCIEBKUiShJ-T2vXV4SJ7KyqAPbYrsigTg44_YAU3bvSDE&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075558&ei=BnJAaK-FFOSjzPsPpvSh0Ag&ip=85.232.208.2&id=o-AGUrDwrilIpFnMLs441YHW4k6Qjo3u6DermpWd3GgwCE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053958%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7kn7d&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLPzVz4CVZZIbQ4KkWLohI-2cXlOmGfFFr3DZvNg1eVGXCeU3Td0gaKOOGmVhdyreT-PJqoN7vJm&spc=l3OVKXo3MDR7T6FM2ZmhLoL8j4O-BM6WgrJ6FiavH-y_1HzNBU2ijTXArVy40xAJTXc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fUcLVpYxc9Fh03PdCFa6twAQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=85QsXshm_xddEQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgYFSg72ht7pHfcyXESJjeF1rT_Z0LjCj-7Nw7GrF9NPUCIQCz7vxRSRCgdeX5f3HuyL3KBtUVrnhgQCYLczUvJ-rwMA%3D%3D&sig=AJfQdSswRQIhANJ22fGb4bhseBWDfkfO69RwynOPNnEskxxrJ8GlZD_CAiBSmuixp6Wp6V8IH79-b9YkqeuHlTksAXajA9EpZdHCeA%3D%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:21 volumio-2 volumio[1044]: verbose: ControllerMpd::parseState Jun 04 18:19:21 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:21 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:21 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:21 volumio-2 volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 18:19:21 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:22 volumio-2 volumio[1044]: 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} Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: No code Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: ControllerMpd::pushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreCommandRouter::servicePushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CorePlayQueue::getTrack 2 Jun 04 18:19:22 volumio-2 volumio[1044]: 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} Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: CURRENT POSITION 2 Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::syncState stateService stop Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: No code Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::pushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreCommandRouter::volumioPushState Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 275ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 280ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: error: updateQueue error: null Jun 04 18:19:22 volumio-2 volumio[1044]: error: updateQueue error: null Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 319ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 324ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: sendMpdCommand playlistinfo took 323 milliseconds Jun 04 18:19:22 volumio-2 volumio[1044]: info: sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075558&ei=BnJAaJryF6bVi9oPgvqc-Qg&ip=85.232.208.2&id=o-APBiFqLqJyS4-qo4vdIEud4wHbsDs9psA0CyoCGbpBuE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053958%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7zn6s&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLNfzmQ4ReagdMkZdM_JL7lSwYfFL7jhAp_GMU4PrHwKPHBoFz-erwaSoidHHiCwlDxErDFNfz1L&spc=l3OVKdXEo5VDvgzjfgxanduInqO4pgccu9qdVnv-CvINbenpT8sVvg3jZ4vN-a8jo4o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=l6Hn3D6K64TtBvkA1wGiNFwQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=pG_WT2T-uZxUmQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgH-Uw9V9x1OZAU4WACXAh1ELWSzcQc6hEYA2ZyUVq_lcCIBz-qezEF6uwcX78wA3efCxrxk30JJKbjbGEJ5QErCvx&sig=AJfQdSswRAIgKFcIen-37ePPyzMzDT-DElFKd3waRmt9UeCcPGUGFFwCIEBKUiShJ-T2vXV4SJ7KyqAPbYrsigTg44_YAU3bvSDE&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" took 334 milliseconds Jun 04 18:19:22 volumio-2 volumio[1044]: info: sendMpdCommand addid "https://rr2---sn-q5u5nugx5h-ha56.googlevideo.com/videoplayback?expire=1749075558&ei=BnJAaK-FFOSjzPsPpvSh0Ag&ip=85.232.208.2&id=o-AGUrDwrilIpFnMLs441YHW4k6Qjo3u6DermpWd3GgwCE&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749053958%2C&mh=ym&mm=31%2C29&mn=sn-q5u5nugx5h-ha56%2Csn-hpa7kn7d&ms=au%2Crdu&mv=m&mvi=2&pl=24&rms=au%2Cau&gcr=mt&initcwndbps=2692500&bui=AY1jyLPzVz4CVZZIbQ4KkWLohI-2cXlOmGfFFr3DZvNg1eVGXCeU3Td0gaKOOGmVhdyreT-PJqoN7vJm&spc=l3OVKXo3MDR7T6FM2ZmhLoL8j4O-BM6WgrJ6FiavH-y_1HzNBU2ijTXArVy40xAJTXc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=fUcLVpYxc9Fh03PdCFa6twAQ&rqh=1&gir=yes&clen=101066207&dur=6612.701&lmt=1739695242233762&mt=1749053606&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5432534&n=85QsXshm_xddEQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgYFSg72ht7pHfcyXESJjeF1rT_Z0LjCj-7Nw7GrF9NPUCIQCz7vxRSRCgdeX5f3HuyL3KBtUVrnhgQCYLczUvJ-rwMA%3D%3D&sig=AJfQdSswRQIhANJ22fGb4bhseBWDfkfO69RwynOPNnEskxxrJ8GlZD_CAiBSmuixp6Wp6V8IH79-b9YkqeuHlTksAXajA9EpZdHCeA%3D%3D&pot=MpgBhC0WO7uW3tmEEbkQnG6Oc2AgdWLjvxwRXCB6YsEbHF9MSLn86KpkzSkt_t3wuE_4OT8dw-oY-IfGYqw52ebI635JGRROxDteGZtnqYtRTwQJfYzuzndg0npROV6lu1rYBB6ODxVq5lJ5uu9XU5XGYMf23ChA3A9am0l7KBQKD-yJ0ipPFPQdL9IWxmZGuxIxD7XAbFs-77g%3D&cver=1.20250219.01.00&t.YouTube" took 342 milliseconds Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 67ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 59ms Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: ControllerMpd::parseTrackInfo Jun 04 18:19:22 volumio-2 volumio[1044]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: MPD COMMAND [object Object] Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 389ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 121ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 121ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 75ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 71ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 04 18:19:22 volumio-2 volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 18:19:22 volumio-2 volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Jun 04 18:19:22 volumio-2 volumio[1044]: ---------------------------- MPD announces system playlist update Jun 04 18:19:22 volumio-2 volumio[1044]: info: Ignoring MPD Status Update Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 38ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 30ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: sendMpdCommand play took 21 milliseconds Jun 04 18:19:22 volumio-2 volumio[1044]: info: sendMpdCommand play took 19 milliseconds Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 18ms Jun 04 18:19:22 volumio-2 volumio[1044]: info: ------------------------------ 15ms Jun 04 18:19:22 volumio-2 volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 18:19:22 volumio-2 volumio[1044]: TypeError: fetch failed Jun 04 18:19:22 volumio-2 volumio[1044]: at HTTPClient.fetch (/data/plugins/music_service/ytmusic/node_modules/undici/index.js:112:15) Jun 04 18:19:22 volumio-2 volumio[1044]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 04 18:19:22 volumio-2 volumio[1044]: at async HTTPClient.fetch (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:8350:22) Jun 04 18:19:22 volumio-2 volumio[1044]: at async Actions.stats (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:26251:12) Jun 04 18:19:22 volumio-2 volumio[1044]: at async TrackInfo.addToWatchHistory (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:23293:12) { Jun 04 18:19:22 volumio-2 volumio[1044]: cause: ConnectTimeoutError: Connect Timeout Error Jun 04 18:19:22 volumio-2 volumio[1044]: at onConnectTimeout (/data/plugins/music_service/youtube2/node_modules/undici/lib/core/connect.js:186:24) Jun 04 18:19:22 volumio-2 volumio[1044]: at /data/plugins/music_service/youtube2/node_modules/undici/lib/core/connect.js:133:46 Jun 04 18:19:22 volumio-2 volumio[1044]: at Immediate._onImmediate (/data/plugins/music_service/youtube2/node_modules/undici/lib/core/connect.js:174:9) Jun 04 18:19:22 volumio-2 volumio[1044]: at process.processImmediate (node:internal/timers:478:21) { Jun 04 18:19:22 volumio-2 volumio[1044]: code: 'UND_ERR_CONNECT_TIMEOUT' Jun 04 18:19:22 volumio-2 volumio[1044]: } Jun 04 18:19:22 volumio-2 volumio[1044]: } Jun 04 18:19:22 volumio-2 volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 18:19:25 volumio-2 sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-04 18:18' Jun 04 18:19:25 volumio-2 sudo[1842]: 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="a42e8353c03f8616e137cf760ea618e88c395461" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 2 16:20:51 UTC 2025" VOLUMIO_VERSION="4.010" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f51ec0ad91e50bdfdb863e902a9e8fa2"