-- Logs begin at Wed 2025-05-14 23:12:10 MSK, end at Thu 2025-05-15 19:02:15 MSK. -- May 15 19:01:14 volumio volumio[6860]: info: CoreCommandRouter::volumioGetQueue May 15 19:01:14 volumio volumio[6860]: info: CoreStateMachine::getQueue May 15 19:01:14 volumio volumio[6860]: info: CorePlayQueue::getQueue May 15 19:01:17 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 15 19:01:17 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 19:01:17 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 19:01:17 volumio volumio[6860]: info: Discovery: Getting this device information May 15 19:01:17 volumio volumio[6860]: info: CoreCommandRouter::volumioGetState May 15 19:01:17 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 19:01:18 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:18 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:18 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:18 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:18 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:30 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 15 19:01:31 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:31 volumio volumio[6860]: info: Preloading song: yandex_music/track/128193370:24353285@user:onyourwave May 15 19:01:31 volumio volumio[6860]: info: Preloading song: yandex_music/track/54990232:7981246@user:onyourwave May 15 19:01:31 volumio volumio[6860]: info: Preloading song: yandex_music/track/91860816:18342718@user:onyourwave May 15 19:01:31 volumio volumio[6860]: info: Preloading song: yandex_music/track/131891776:33591006@user:onyourwave May 15 19:01:31 volumio volumio[6860]: info: Preloading song: yandex_music/track/45977476:6185427@user:onyourwave May 15 19:01:32 volumio volumio[6860]: info: Exploding uri yandex_music/track/128193370:24353285@user:onyourwave in service yandex_music May 15 19:01:32 volumio volumio[6860]: info: Exploding uri yandex_music/track/54990232:7981246@user:onyourwave in service yandex_music May 15 19:01:32 volumio volumio[6860]: info: Exploding uri yandex_music/track/91860816:18342718@user:onyourwave in service yandex_music May 15 19:01:32 volumio volumio[6860]: info: Exploding uri yandex_music/track/131891776:33591006@user:onyourwave in service yandex_music May 15 19:01:32 volumio volumio[6860]: info: Exploding uri yandex_music/track/45977476:6185427@user:onyourwave in service yandex_music May 15 19:01:34 volumio volumio[6860]: info: CoreCommandRouter::volumioPlay May 15 19:01:34 volumio volumio[6860]: info: CoreStateMachine::play index undefined May 15 19:01:34 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 19:01:34 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:34 volumio volumio[6860]: info: CoreStateMachine::startPlaybackTimer May 15 19:01:34 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand stop May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand stop took 8 milliseconds May 15 19:01:34 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand clear May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:34 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:34 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand status took 12 milliseconds May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand clear took 12 milliseconds May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand status took 9 milliseconds May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand status took 7 milliseconds May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:34 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:34 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:34 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:34 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:34 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 19:01:34 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:34 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:34 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:34 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:34 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:34 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:34 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:34 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:34 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:34 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 65ms May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 63ms May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:34 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:34 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 75ms May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand playlistinfo took 67 milliseconds May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 9ms May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 6ms May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::parseTrackInfo May 15 19:01:34 volumio volumio[6860]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 95ms May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-std-31.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3336ecf7f0030e709587ef61e1b7ac60a3c708b7872eb1be8b9b1a614ac86f53%2Clid%3D1563%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683391d4%2F0%2F1612364%2F5e2b4803.203027968.5.138764823%2Fflac-mp4&ext=.flac" May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-std-31.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3336ecf7f0030e709587ef61e1b7ac60a3c708b7872eb1be8b9b1a614ac86f53%2Clid%3D1563%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683391d4%2F0%2F1612364%2F5e2b4803.203027968.5.138764823%2Fflac-mp4&ext=.flac" took 4 milliseconds May 15 19:01:34 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:34 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:34 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 12ms May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 6ms May 15 19:01:34 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 19:01:34 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand play May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: May 15 19:01:34 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:34 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 11ms May 15 19:01:34 volumio volumio[6860]: info: sendMpdCommand play took 8 milliseconds May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:34 volumio volumio[6860]: info: ------------------------------ 4ms May 15 19:01:38 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:38 volumio volumio[6860]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 19:01:38 volumio volumio[6860]: info: CoreStateMachine::ClearQueue May 15 19:01:38 volumio volumio[6860]: info: CoreStateMachine::stop May 15 19:01:38 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 19:01:38 volumio volumio[6860]: info: CorePlayQueue::clearPlayQueue May 15 19:01:38 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:38 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:38 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:38 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:38 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:38 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/128193370:24353285@user:onyourwave May 15 19:01:38 volumio volumio[6860]: info: Using cached record of: yandex_music/track/128193370:24353285@user:onyourwave May 15 19:01:38 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:39 volumio volumio[6860]: info: CoreCommandRouter::volumioPlay May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::play index 0 May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:39 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:39 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/54990232:7981246@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: Using cached record of: yandex_music/track/54990232:7981246@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/91860816:18342718@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: Using cached record of: yandex_music/track/91860816:18342718@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/131891776:33591006@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: Using cached record of: yandex_music/track/131891776:33591006@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/45977476:6185427@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: Using cached record of: yandex_music/track/45977476:6185427@user:onyourwave May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::stop May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 19:01:39 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::play index undefined May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::startPlaybackTimer May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand stop May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand stop took 10 milliseconds May 15 19:01:39 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand clear May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:39 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:39 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand status took 13 milliseconds May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand clear took 12 milliseconds May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand status took 9 milliseconds May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand status took 7 milliseconds May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:39 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:39 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:39 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 19:01:39 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:39 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:39 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:39 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:39 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:39 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:39 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 52ms May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 50ms May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:39 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:39 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 65ms May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand playlistinfo took 60 milliseconds May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 11ms May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 9ms May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::parseTrackInfo May 15 19:01:39 volumio volumio[6860]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 84ms May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-58.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Daff27a73de3379f15a093ab035637d0ff2d935a2ee957a4a47935076c69ac954%2Clid%3D1530%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68344052%2F0%2F11911782%2F2b04ff0f.185290788.10.128193370%2F320.mp3&ext=.mp3" May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-58.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Daff27a73de3379f15a093ab035637d0ff2d935a2ee957a4a47935076c69ac954%2Clid%3D1530%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68344052%2F0%2F11911782%2F2b04ff0f.185290788.10.128193370%2F320.mp3&ext=.mp3" took 4 milliseconds May 15 19:01:39 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:39 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:39 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 13ms May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 8ms May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:39 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 19:01:39 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand play May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: May 15 19:01:39 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:39 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 12ms May 15 19:01:39 volumio volumio[6860]: info: sendMpdCommand play took 7 milliseconds May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:39 volumio volumio[6860]: info: ------------------------------ 5ms May 15 19:01:43 volumio volumio[6860]: info: CoreCommandRouter::volumioPlay May 15 19:01:43 volumio volumio[6860]: info: CoreStateMachine::play index undefined May 15 19:01:43 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 19:01:43 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:43 volumio volumio[6860]: info: CoreStateMachine::startPlaybackTimer May 15 19:01:43 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:43 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand stop May 15 19:01:43 volumio volumio[6860]: info: May 15 19:01:43 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:43 volumio volumio[6860]: info: sendMpdCommand stop took 9 milliseconds May 15 19:01:43 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:43 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:43 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand clear May 15 19:01:43 volumio volumio[6860]: info: May 15 19:01:43 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:43 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:43 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:43 volumio volumio[6860]: info: May 15 19:01:43 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:43 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:43 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:43 volumio volumio[6860]: info: May 15 19:01:43 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:43 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:43 volumio volumio[6860]: info: sendMpdCommand status took 11 milliseconds May 15 19:01:43 volumio volumio[6860]: info: sendMpdCommand clear took 11 milliseconds May 15 19:01:43 volumio volumio[6860]: info: sendMpdCommand status took 8 milliseconds May 15 19:01:43 volumio volumio[6860]: info: sendMpdCommand status took 8 milliseconds May 15 19:01:43 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:44 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 19:01:44 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:44 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:44 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:44 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:44 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:44 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:44 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 19:01:44 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:44 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:44 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:44 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:44 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:44 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:44 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:44 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:44 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:44 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 51ms May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 49ms May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:44 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:44 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 60ms May 15 19:01:44 volumio volumio[6860]: info: sendMpdCommand playlistinfo took 53 milliseconds May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 8ms May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:44 volumio volumio[6860]: verbose: ControllerMpd::parseTrackInfo May 15 19:01:44 volumio volumio[6860]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 77ms May 15 19:01:44 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9ef96f11a5f3d8802c8109009e785c4db63cc05abd0b33d3fb3d979a43ebd66f%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68344052%2F0%2F11911782%2F2b04ff0f.185290788.10.128193370%2F320.mp3&ext=.mp3" May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-spbmiran-19.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9ef96f11a5f3d8802c8109009e785c4db63cc05abd0b33d3fb3d979a43ebd66f%2Clid%3D323%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68344052%2F0%2F11911782%2F2b04ff0f.185290788.10.128193370%2F320.mp3&ext=.mp3" took 7 milliseconds May 15 19:01:44 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:44 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:44 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 16ms May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 18ms May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 15ms May 15 19:01:44 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 19:01:44 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand play May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: info: May 15 19:01:44 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:44 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 23ms May 15 19:01:44 volumio volumio[6860]: info: sendMpdCommand play took 11 milliseconds May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 10ms May 15 19:01:44 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:45 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/84083066:15958635@user:onyourwave May 15 19:01:45 volumio volumio[6860]: info: Exploding uri yandex_music/track/84083066:15958635@user:onyourwave in service yandex_music May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:45 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/135611824:35177316@user:onyourwave May 15 19:01:45 volumio volumio[6860]: info: Exploding uri yandex_music/track/135611824:35177316@user:onyourwave in service yandex_music May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:45 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/95224825:21173021@user:onyourwave May 15 19:01:45 volumio volumio[6860]: info: Exploding uri yandex_music/track/95224825:21173021@user:onyourwave in service yandex_music May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:45 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/118362573:27808355@user:onyourwave May 15 19:01:45 volumio volumio[6860]: info: Exploding uri yandex_music/track/118362573:27808355@user:onyourwave in service yandex_music May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:45 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:45 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/63282863:10054937@user:onyourwave May 15 19:01:45 volumio volumio[6860]: info: Exploding uri yandex_music/track/63282863:10054937@user:onyourwave in service yandex_music May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:45 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:45 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:47 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 19:01:47 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 19:01:47 volumio volumio[6860]: info: Discovery: Getting this device information May 15 19:01:47 volumio volumio[6860]: info: CoreCommandRouter::volumioGetState May 15 19:01:47 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 19:01:53 volumio volumio[6860]: info: CoreCommandRouter::volumioPlay May 15 19:01:53 volumio volumio[6860]: info: CoreStateMachine::play index undefined May 15 19:01:53 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 19:01:53 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:53 volumio volumio[6860]: info: CoreStateMachine::startPlaybackTimer May 15 19:01:53 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:53 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand stop May 15 19:01:53 volumio volumio[6860]: info: May 15 19:01:53 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:53 volumio volumio[6860]: info: sendMpdCommand stop took 9 milliseconds May 15 19:01:53 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:53 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:53 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand clear May 15 19:01:53 volumio volumio[6860]: info: May 15 19:01:53 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:53 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:53 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:53 volumio volumio[6860]: info: May 15 19:01:53 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:01:53 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: sendMpdCommand status took 8 milliseconds May 15 19:01:54 volumio volumio[6860]: info: sendMpdCommand clear took 9 milliseconds May 15 19:01:54 volumio volumio[6860]: info: sendMpdCommand status took 7 milliseconds May 15 19:01:54 volumio volumio[6860]: info: sendMpdCommand status took 4 milliseconds May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:01:54 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:54 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:54 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:54 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:54 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:54 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:54 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:54 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:01:54 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:01:54 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:01:54 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:54 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:01:54 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:01:54 volumio volumio[6860]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 44ms May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 43ms May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:54 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:54 volumio volumio[6860]: error: updateQueue error: null May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 55ms May 15 19:01:54 volumio volumio[6860]: info: sendMpdCommand playlistinfo took 52 milliseconds May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 5ms May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::parseTrackInfo May 15 19:01:54 volumio volumio[6860]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 76ms May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-std-37.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D8ff217196f9549522734f7521041598435b3b5b12bd1fd24726e52ff6a6796bc%2Clid%3D1561%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68344052%2F0%2F11911782%2F2b04ff0f.185290788.10.128193370%2F320.mp3&ext=.mp3" May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-std-37.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D8ff217196f9549522734f7521041598435b3b5b12bd1fd24726e52ff6a6796bc%2Clid%3D1561%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68344052%2F0%2F11911782%2F2b04ff0f.185290788.10.128193370%2F320.mp3&ext=.mp3" took 4 milliseconds May 15 19:01:54 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:54 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:54 volumio volumio[6860]: verbose: MPD COMMAND [object Object] May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 9ms May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 6ms May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 4ms May 15 19:01:54 volumio volumio[6860]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 19:01:54 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand play May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: May 15 19:01:54 volumio volumio[6860]: ---------------------------- MPD announces system playlist update May 15 19:01:54 volumio volumio[6860]: info: Ignoring MPD Status Update May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 11ms May 15 19:01:54 volumio volumio[6860]: info: sendMpdCommand play took 8 milliseconds May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 7ms May 15 19:01:54 volumio volumio[6860]: info: ------------------------------ 5ms May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:55 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/123480351:30110978@user:onyourwave May 15 19:01:55 volumio volumio[6860]: info: Exploding uri yandex_music/track/123480351:30110978@user:onyourwave in service yandex_music May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:55 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/105014042:22675744@user:onyourwave May 15 19:01:55 volumio volumio[6860]: info: Exploding uri yandex_music/track/105014042:22675744@user:onyourwave in service yandex_music May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:55 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/51617755:7213942@user:onyourwave May 15 19:01:55 volumio volumio[6860]: info: Exploding uri yandex_music/track/51617755:7213942@user:onyourwave in service yandex_music May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:55 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/121649027:29279849@user:onyourwave May 15 19:01:55 volumio volumio[6860]: info: Exploding uri yandex_music/track/121649027:29279849@user:onyourwave in service yandex_music May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioAddQueueItems May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::addQueueItems May 15 19:01:55 volumio volumio[6860]: info: Preload queue cleared May 15 19:01:55 volumio volumio[6860]: info: Adding Item to queue: yandex_music/track/46695683:6306633@user:onyourwave May 15 19:01:55 volumio volumio[6860]: info: Exploding uri yandex_music/track/46695683:6306633@user:onyourwave in service yandex_music May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:55 volumio volumio[6860]: info: CoreCommandRouter::volumioPushQueue May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::saveQueue May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CoreStateMachine::updateTrackBlock May 15 19:01:55 volumio volumio[6860]: info: CorePlayQueue::getTrackBlock May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioNext May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::next May 15 19:02:02 volumio volumio[6860]: info: ControllerMpd::next May 15 19:02:02 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand next May 15 19:02:02 volumio volumio[6860]: info: May 15 19:02:02 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:02:02 volumio volumio[6860]: info: sendMpdCommand next took 4 milliseconds May 15 19:02:02 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:02:02 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:02:02 volumio volumio[6860]: info: May 15 19:02:02 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:02:02 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:02:02 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:02:02 volumio volumio[6860]: info: May 15 19:02:02 volumio volumio[6860]: ---------------------------- MPD announces state update: player May 15 19:02:02 volumio volumio[6860]: info: ControllerMpd::getState May 15 19:02:02 volumio volumio[6860]: verbose: ControllerMpd::sendMpdCommand status May 15 19:02:02 volumio volumio[6860]: info: sendMpdCommand status took 8 milliseconds May 15 19:02:02 volumio volumio[6860]: info: sendMpdCommand status took 6 milliseconds May 15 19:02:02 volumio volumio[6860]: info: sendMpdCommand status took 3 milliseconds May 15 19:02:02 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:02:02 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:02:02 volumio volumio[6860]: verbose: ControllerMpd::parseState May 15 19:02:02 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:02:02 volumio volumio[6860]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 19:02:02 volumio volumio[6860]: verbose: CURRENT POSITION 0 May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::syncState stateService stop May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::syncState currentStatus stop May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: No code May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:02:02 volumio volumio[6860]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 19:02:02 volumio volumio[6860]: verbose: CURRENT POSITION 0 May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::syncState stateService stop May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::syncState currentStatus stop May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: No code May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: ControllerMpd::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::servicePushState May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: CorePlayQueue::getTrack 0 May 15 19:02:02 volumio volumio[6860]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 19:02:02 volumio volumio[6860]: verbose: CURRENT POSITION 0 May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::syncState stateService stop May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::syncState currentStatus stop May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:02 volumio volumio[6860]: info: No code May 15 19:02:02 volumio volumio[6860]: info: CoreStateMachine::pushState May 15 19:02:02 volumio volumio[6860]: info: CoreCommandRouter::volumioPushState May 15 19:02:03 volumio volumio[6860]: info: ------------------------------ 152ms May 15 19:02:03 volumio volumio[6860]: info: ------------------------------ 151ms May 15 19:02:03 volumio volumio[6860]: info: ------------------------------ 150ms May 15 19:02:14 volumio volumio[6860]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 19:02:14 volumio volumio[6860]: Error: connect ETIMEDOUT 37.9.101.204:443 May 15 19:02:14 volumio volumio[6860]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 15 19:02:14 volumio volumio[6860]: errno: -110, May 15 19:02:14 volumio volumio[6860]: code: 'ETIMEDOUT', May 15 19:02:14 volumio volumio[6860]: syscall: 'connect', May 15 19:02:14 volumio volumio[6860]: address: '37.9.101.204', May 15 19:02:14 volumio volumio[6860]: port: 443 May 15 19:02:14 volumio volumio[6860]: } May 15 19:02:14 volumio volumio[6860]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 19:02:15 volumio sudo[8528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 19:01 May 15 19:02:15 volumio sudo[8528]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"