-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Fri 2025-05-23 22:23:30 MSK. -- May 23 22:22:01 volumio volumio[821]: info: Preload queue cleared May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::ClearQueue May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::serviceStop May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::serviceStop May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:01 volumio volumio[821]: info: ControllerMpd::stop May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::clearPlayQueue May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::saveQueue May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::addQueueItems May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::addQueueItems May 23 22:22:01 volumio volumio[821]: info: Preload queue cleared May 23 22:22:01 volumio volumio[821]: info: Adding Item to queue: yandex_music/artist/191175 May 23 22:22:01 volumio volumio[821]: info: Using cached record of: yandex_music/artist/191175 May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::saveQueue May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::play index 0 May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:01 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand stop took 112 milliseconds May 23 22:22:01 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand stop took 60 milliseconds May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:01 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:01 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand status took 6 milliseconds May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand clear took 6 milliseconds May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand status took 5 milliseconds May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:01 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:01 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:01 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:01 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 15ms May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:01 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:01 volumio volumio[821]: error: updateQueue error: null May 23 22:22:01 volumio volumio[821]: error: updateQueue error: null May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 23ms May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand playlistinfo took 21 milliseconds May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:01 volumio volumio[821]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 30ms May 23 22:22:01 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:01 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-95.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9136779d3324b51a3934bdba0d8f0a83eaba1ecd52558af78c1958cb645a3492%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683dfc69%2F0%2F14030689%2Ff303d5cb.203330843.6.139044931%2F320.mp3&ext=.mp3" May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:01 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-95.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9136779d3324b51a3934bdba0d8f0a83eaba1ecd52558af78c1958cb645a3492%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683dfc69%2F0%2F14030689%2Ff303d5cb.203330843.6.139044931%2F320.mp3&ext=.mp3" took 2 milliseconds May 23 22:22:01 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:22:01 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:01 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:01 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 1ms May 23 22:22:01 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:01 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:22:01 volumio volumio[821]: info: May 23 22:22:01 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:01 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:01 volumio volumio[821]: info: sendMpdCommand play took 1 milliseconds May 23 22:22:01 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:02 volumio volumio[821]: info: May 23 22:22:02 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:02 volumio volumio[821]: info: May 23 22:22:02 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:02 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:22:02 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:22:02 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:22:02 volumio volumio[821]: info: May 23 22:22:02 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand status took 20 milliseconds May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand status took 20 milliseconds May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:02 volumio volumio[821]: info: May 23 22:22:02 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand status took 3 milliseconds May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:02 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":137,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tom Judge Intro","artist":"AC/DC","album":"Fort Lauderdale 1977","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-95.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9136779d3324b51a3934bdba0d8f0a83eaba1ecd52558af78c1958cb645a3492%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683dfc69%2F0%2F14030689%2Ff303d5cb.203330843.6.139044931%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:02 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:02 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":137,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tom Judge Intro","artist":"AC/DC","album":"Fort Lauderdale 1977","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-95.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9136779d3324b51a3934bdba0d8f0a83eaba1ecd52558af78c1958cb645a3492%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683dfc69%2F0%2F14030689%2Ff303d5cb.203330843.6.139044931%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:02 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:02 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:02 volumio volumio[821]: info: ------------------------------ 32ms May 23 22:22:02 volumio volumio[821]: info: ------------------------------ 36ms May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand playlistinfo took 14 milliseconds May 23 22:22:02 volumio volumio[821]: info: sendMpdCommand playlistinfo took 14 milliseconds May 23 22:22:02 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:02 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1260,"duration":137,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tom Judge Intro","artist":"AC/DC","album":"Fort Lauderdale 1977","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-95.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9136779d3324b51a3934bdba0d8f0a83eaba1ecd52558af78c1958cb645a3492%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683dfc69%2F0%2F14030689%2Ff303d5cb.203330843.6.139044931%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:02 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:02 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:02 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:02 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1386,"duration":137,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tom Judge Intro","artist":"AC/DC","album":"Fort Lauderdale 1977","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-95.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9136779d3324b51a3934bdba0d8f0a83eaba1ecd52558af78c1958cb645a3492%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683dfc69%2F0%2F14030689%2Ff303d5cb.203330843.6.139044931%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:02 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:02 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:02 volumio volumio[821]: info: ------------------------------ 71ms May 23 22:22:02 volumio volumio[821]: info: ------------------------------ 69ms May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:02 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:02 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:02 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:02 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:02 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:02 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:02 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:02 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:02 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:02 volumio volumio[821]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 23 22:22:02 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 22:22:08 volumio go-librespot[1189]: time="2025-05-23T22:22:08+03:00" level=trace msg="sent dealer ping" May 23 22:22:08 volumio go-librespot[1189]: time="2025-05-23T22:22:08+03:00" level=trace msg="received dealer pong" May 23 22:22:13 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::play index 13 May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:22:13 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:13 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:13 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:13 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::serviceStop May 23 22:22:13 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:22:13 volumio volumio[821]: info: CoreCommandRouter::serviceStop May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:13 volumio volumio[821]: info: ControllerMpd::stop May 23 22:22:13 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:13 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:13 volumio volumio[821]: info: May 23 22:22:13 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:13 volumio volumio[821]: info: sendMpdCommand stop took 11 milliseconds May 23 22:22:13 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:13 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:13 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:13 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:22:13 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:13 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:13 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:22:13 volumio volumio[821]: info: sendMpdCommand stop took 2 milliseconds May 23 22:22:13 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:13 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:13 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:22:13 volumio volumio[821]: info: May 23 22:22:13 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:13 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:13 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:14 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand playlistinfo took 34 milliseconds May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand clear took 34 milliseconds May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand status took 28 milliseconds May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 55ms May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 52ms May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:14 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:14 volumio volumio[821]: error: updateQueue error: null May 23 22:22:14 volumio volumio[821]: error: updateQueue error: null May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 30ms May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 4ms May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df36518162e40327595f69eb8bd0dbfd31ae0c888eb4a238d0463803a53ea2f84%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3" May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:14 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df36518162e40327595f69eb8bd0dbfd31ae0c888eb4a238d0463803a53ea2f84%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3" took 2 milliseconds May 23 22:22:14 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:14 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:14 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:14 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand play took 3 milliseconds May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:14 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:22:14 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:22:14 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:22:14 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:22:14 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:22:14 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand status took 19 milliseconds May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand status took 19 milliseconds May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:14 volumio volumio[821]: info: May 23 22:22:14 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand status took 8 milliseconds May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand playlistinfo took 8 milliseconds May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand playlistinfo took 9 milliseconds May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand status took 8 milliseconds May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df36518162e40327595f69eb8bd0dbfd31ae0c888eb4a238d0463803a53ea2f84%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:14 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df36518162e40327595f69eb8bd0dbfd31ae0c888eb4a238d0463803a53ea2f84%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:14 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:14 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 35ms May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 38ms May 23 22:22:14 volumio volumio[821]: info: sendMpdCommand playlistinfo took 13 milliseconds May 23 22:22:14 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1008,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df36518162e40327595f69eb8bd0dbfd31ae0c888eb4a238d0463803a53ea2f84%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:14 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:14 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:14 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1008,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df36518162e40327595f69eb8bd0dbfd31ae0c888eb4a238d0463803a53ea2f84%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:14 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:14 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 43ms May 23 22:22:14 volumio volumio[821]: info: ------------------------------ 42ms May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:14 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:14 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:14 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:14 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::play index 13 May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::serviceStop May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::serviceStop May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:16 volumio volumio[821]: info: ControllerMpd::stop May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:16 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:16 volumio volumio[821]: info: May 23 22:22:16 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:16 volumio volumio[821]: info: sendMpdCommand stop took 12 milliseconds May 23 22:22:16 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:16 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:22:16 volumio volumio[821]: info: sendMpdCommand stop took 2 milliseconds May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:22:16 volumio volumio[821]: info: May 23 22:22:16 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:16 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:16 volumio volumio[821]: info: May 23 22:22:16 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:16 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 29 milliseconds May 23 22:22:16 volumio volumio[821]: info: sendMpdCommand clear took 29 milliseconds May 23 22:22:16 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:16 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:16 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:22:16 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:16 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:16 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:16 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:22:16 volumio volumio[821]: info: ------------------------------ 45ms May 23 22:22:16 volumio volumio[821]: info: ------------------------------ 15ms May 23 22:22:16 volumio volumio[821]: info: May 23 22:22:16 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:16 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:16 volumio volumio[821]: error: updateQueue error: null May 23 22:22:16 volumio volumio[821]: error: updateQueue error: null May 23 22:22:16 volumio volumio[821]: info: ------------------------------ 21ms May 23 22:22:16 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5f295d837c1358878ee872b4348dbf1bac59534be9a57ebe456e0f224e237a0f%2Clid%3D1560%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3" May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5f295d837c1358878ee872b4348dbf1bac59534be9a57ebe456e0f224e237a0f%2Clid%3D1560%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3" took 1 milliseconds May 23 22:22:17 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:22:17 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand play took 1 milliseconds May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 1ms May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:17 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:22:17 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:22:17 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand status took 18 milliseconds May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand status took 18 milliseconds May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:17 volumio volumio[821]: info: May 23 22:22:17 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand status took 3 milliseconds May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:17 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5f295d837c1358878ee872b4348dbf1bac59534be9a57ebe456e0f224e237a0f%2Clid%3D1560%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:17 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:17 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":426,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5f295d837c1358878ee872b4348dbf1bac59534be9a57ebe456e0f224e237a0f%2Clid%3D1560%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:17 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:17 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 29ms May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 31ms May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand playlistinfo took 11 milliseconds May 23 22:22:17 volumio volumio[821]: info: sendMpdCommand playlistinfo took 11 milliseconds May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:17 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1008,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5f295d837c1358878ee872b4348dbf1bac59534be9a57ebe456e0f224e237a0f%2Clid%3D1560%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:17 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:17 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:17 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:17 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1008,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5f295d837c1358878ee872b4348dbf1bac59534be9a57ebe456e0f224e237a0f%2Clid%3D1560%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:17 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:17 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 48ms May 23 22:22:17 volumio volumio[821]: info: ------------------------------ 46ms May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:17 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:17 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:17 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:17 volumio volumio[821]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 23 22:22:17 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 22:22:19 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::play index 13 May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:22:19 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:19 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:19 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:19 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::serviceStop May 23 22:22:19 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:19 volumio volumio[821]: info: CoreCommandRouter::serviceStop May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:19 volumio volumio[821]: info: ControllerMpd::stop May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:19 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand stop took 15 milliseconds May 23 22:22:19 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:19 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:22:19 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:19 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand status took 31 milliseconds May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand stop took 29 milliseconds May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand status took 28 milliseconds May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand clear took 2 milliseconds May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:19 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:19 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:19 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:19 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:19 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:19 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:22:19 volumio volumio[821]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 43ms May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 40ms May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:19 volumio volumio[821]: error: updateQueue error: null May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 16ms May 23 22:22:19 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:19 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 23 22:22:19 volumio volumio[821]: error: updateQueue error: null May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 10ms May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3" May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3" took 1 milliseconds May 23 22:22:19 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:22:19 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 1ms May 23 22:22:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:22:19 volumio volumio[821]: info: May 23 22:22:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:22:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:19 volumio volumio[821]: info: sendMpdCommand play took 2 milliseconds May 23 22:22:19 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:22:20 volumio volumio[821]: info: May 23 22:22:20 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:20 volumio volumio[821]: info: May 23 22:22:20 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:20 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:22:20 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:22:20 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:22:20 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:22:20 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:22:20 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:22:20 volumio volumio[821]: info: May 23 22:22:20 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:20 volumio volumio[821]: info: sendMpdCommand status took 23 milliseconds May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:20 volumio volumio[821]: info: sendMpdCommand status took 21 milliseconds May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:20 volumio volumio[821]: info: May 23 22:22:20 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:20 volumio volumio[821]: info: sendMpdCommand status took 4 milliseconds May 23 22:22:20 volumio volumio[821]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 22:22:20 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds May 23 22:22:20 volumio volumio[821]: info: sendMpdCommand status took 3 milliseconds May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:20 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:20 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:20 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:20 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:20 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:20 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:20 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:20 volumio volumio[821]: info: ------------------------------ 33ms May 23 22:22:20 volumio volumio[821]: info: ------------------------------ 39ms May 23 22:22:20 volumio volumio[821]: info: sendMpdCommand playlistinfo took 15 milliseconds May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:20 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:20 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:20 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1008,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:20 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:20 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:20 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:20 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:20 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1008,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:20 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:22:20 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:20 volumio volumio[821]: info: ------------------------------ 33ms May 23 22:22:20 volumio volumio[821]: info: ------------------------------ 32ms May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:20 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:20 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:20 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:20 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:20 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:20 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:20 volumio volumio[821]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 23 22:22:20 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 22:22:20 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:20 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:32 volumio volumio[821]: info: Preload queue cleared May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::ClearQueue May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::serviceStop May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::serviceStop May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:22:32 volumio volumio[821]: info: ControllerMpd::stop May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::clearPlayQueue May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::saveQueue May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::addQueueItems May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::addQueueItems May 23 22:22:32 volumio volumio[821]: info: Preload queue cleared May 23 22:22:32 volumio volumio[821]: info: Adding Item to queue: yandex_music/album/2035783 May 23 22:22:32 volumio volumio[821]: info: Exploding uri yandex_music/album/2035783 in service yandex_music May 23 22:22:32 volumio volumio[821]: info: May 23 22:22:32 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:32 volumio volumio[821]: info: sendMpdCommand stop took 11 milliseconds May 23 22:22:32 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:32 volumio volumio[821]: info: May 23 22:22:32 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:22:32 volumio volumio[821]: info: ControllerMpd::getState May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:22:32 volumio volumio[821]: info: sendMpdCommand status took 4 milliseconds May 23 22:22:32 volumio volumio[821]: info: sendMpdCommand status took 1 milliseconds May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:22:32 volumio volumio[821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 22:22:32 volumio volumio[821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:32 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:22:32 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:32 volumio volumio[821]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:32 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::syncState stateService stop May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:32 volumio volumio[821]: info: No code May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:32 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:32 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:32 volumio volumio[821]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstruck","artist":"AC/DC","album":"River Plate 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-27.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250aec5e9e8094fe2be4e0725c33bda99bfcb70a4bf0647cbc1576b40bc9c04b%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ead7e%2F0%2F5236183%2F4e1c17aa.203326593.6.139042913%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:22:32 volumio volumio[821]: verbose: CURRENT POSITION 13 May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::syncState stateService stop May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:32 volumio volumio[821]: info: No code May 23 22:22:32 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:22:32 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:22:32 volumio volumio[821]: info: ------------------------------ 26ms May 23 22:22:32 volumio volumio[821]: info: ------------------------------ 25ms May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue May 23 22:22:36 volumio volumio[821]: info: CoreStateMachine::getQueue May 23 22:22:36 volumio volumio[821]: info: CorePlayQueue::getQueue May 23 22:22:36 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 23 22:22:36 volumio volumio[821]: info: touch_display: Setting screensaver timeout to 0 seconds. May 23 22:22:38 volumio go-librespot[1189]: time="2025-05-23T22:22:38+03:00" level=trace msg="sent dealer ping" May 23 22:22:38 volumio go-librespot[1189]: time="2025-05-23T22:22:38+03:00" level=trace msg="received dealer pong" May 23 22:22:39 volumio volumio[821]: info: Preload queue cleared May 23 22:22:39 volumio volumio[821]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 22:22:39 volumio volumio[821]: info: CoreStateMachine::ClearQueue May 23 22:22:39 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:22:39 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:22:39 volumio volumio[821]: info: CorePlayQueue::clearPlayQueue May 23 22:22:39 volumio volumio[821]: info: CorePlayQueue::saveQueue May 23 22:22:39 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue May 23 22:22:39 volumio volumio[821]: info: CoreStateMachine::addQueueItems May 23 22:22:39 volumio volumio[821]: info: CorePlayQueue::addQueueItems May 23 22:22:39 volumio volumio[821]: info: Preload queue cleared May 23 22:22:39 volumio volumio[821]: info: Adding Item to queue: yandex_music/album/2035783 May 23 22:22:39 volumio volumio[821]: info: Exploding uri yandex_music/album/2035783 in service yandex_music May 23 22:22:41 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 23 22:22:43 volumio volumio-remote-updater[592]: [2025-05-23 22:22:43] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 23 22:22:43 volumio volumio-remote-updater[592]: [2025-05-23 22:22:43] [warning] got non-close frame while closing May 23 22:22:48 volumio volumio-remote-updater[592]: [2025-05-23 22:22:48] [connect] Successful connection May 23 22:22:48 volumio volumio-remote-updater[592]: [2025-05-23 22:22:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748028168 101 May 23 22:22:48 volumio volumio[821]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 11 May 23 22:22:51 volumio volumio[821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 23 22:22:51 volumio volumio[821]: info: CoreCommandRouter::volumioGetState May 23 22:22:51 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:51 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:22:54 volumio volumio[821]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::volumioGetVisibleSources May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::volumioGetState May 23 22:22:54 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::volumioGetState May 23 22:22:54 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 22:22:54 volumio volumio[821]: info: Received Get System Info May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 22:22:54 volumio volumio[821]: info: Discovery: Getting this device information May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::volumioGetState May 23 22:22:54 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::volumioGetState May 23 22:22:54 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:22:54 volumio volumio[821]: info: Listing playlists May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 22:22:54 volumio volumio[821]: info: FusionDsp - no preset used May 23 22:22:54 volumio volumio[821]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 22:22:54 volumio volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 22:22:54 volumio volumio[821]: info: FusionDsp - no preset used May 23 22:22:54 volumio volumio[821]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 23 22:22:59 volumio volumio[821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 11 May 23 22:22:59 volumio volumio[821]: info: CoreCommandRouter::volumioGetState May 23 22:22:59 volumio volumio[821]: info: CorePlayQueue::getTrack 13 May 23 22:23:02 volumio volumio[821]: info: Preload queue cleared May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120911:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120922:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120912:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120921:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120917:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120914:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120910:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120923:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120920:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120915:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120913:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120918:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120919:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120916:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Preloading song: yandex_music/track/16120909:2035783@2035783 May 23 22:23:02 volumio volumio[821]: info: Exploding uri yandex_music/track/16120911:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120922:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120912:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120921:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120917:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120914:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120910:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120923:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120920:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120915:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120913:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120918:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120919:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120916:2035783@2035783 in service yandex_music May 23 22:23:03 volumio volumio[821]: info: Exploding uri yandex_music/track/16120909:2035783@2035783 in service yandex_music May 23 22:23:06 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue May 23 22:23:06 volumio volumio[821]: info: CorePlayQueue::saveQueue May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:23:06 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:23:06 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::play index 0 May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:06 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:23:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:23:06 volumio volumio[821]: info: sendMpdCommand stop took 1 milliseconds May 23 22:23:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:23:06 volumio volumio[821]: info: sendMpdCommand clear took 0 milliseconds May 23 22:23:06 volumio volumio[821]: info: May 23 22:23:06 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:06 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:06 volumio volumio[821]: info: May 23 22:23:06 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:06 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:06 volumio volumio[821]: error: updateQueue error: null May 23 22:23:06 volumio volumio[821]: error: updateQueue error: null May 23 22:23:06 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:06 volumio volumio[821]: info: ------------------------------ 1ms May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-8.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7498a162ffefd1d5ab25e3d0686623a17ac4d1d45dc4a53bfb1b744208f6a771%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ecc74%2F0%2F55177%2F6a9d5198.42981995.8.16120911%2F320.mp3&ext=.mp3" May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:07 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-8.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7498a162ffefd1d5ab25e3d0686623a17ac4d1d45dc4a53bfb1b744208f6a771%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ecc74%2F0%2F55177%2F6a9d5198.42981995.8.16120911%2F320.mp3&ext=.mp3" took 2 milliseconds May 23 22:23:07 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:23:07 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:07 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:07 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:07 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand play took 2 milliseconds May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:07 volumio volumio[821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 23 22:23:07 volumio volumio[821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 23 22:23:07 volumio volumio[821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:07 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:23:07 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:23:07 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:23:07 volumio volumio[821]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 23 22:23:07 volumio volumio[821]: info: FusionDsp - ---- read samplerate from file: 44100 May 23 22:23:07 volumio volumio[821]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand status took 15 milliseconds May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand status took 14 milliseconds May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:07 volumio volumio[821]: info: May 23 22:23:07 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand status took 4 milliseconds May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand playlistinfo took 4 milliseconds May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand status took 3 milliseconds May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:23:07 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:07 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Highway to Hell","artist":"Masters of Rock","album":"AC/DC - The Best Of - 15 Massive AC/DC Rock Tributes","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-8.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7498a162ffefd1d5ab25e3d0686623a17ac4d1d45dc4a53bfb1b744208f6a771%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ecc74%2F0%2F55177%2F6a9d5198.42981995.8.16120911%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:23:07 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:23:07 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:07 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Highway to Hell","artist":"Masters of Rock","album":"AC/DC - The Best Of - 15 Massive AC/DC Rock Tributes","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-8.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7498a162ffefd1d5ab25e3d0686623a17ac4d1d45dc4a53bfb1b744208f6a771%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ecc74%2F0%2F55177%2F6a9d5198.42981995.8.16120911%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:23:07 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:23:07 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 28ms May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 30ms May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand playlistinfo took 11 milliseconds May 23 22:23:07 volumio volumio[821]: info: sendMpdCommand playlistinfo took 10 milliseconds May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:07 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:23:07 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:07 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1474,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Highway to Hell","artist":"Masters of Rock","album":"AC/DC - The Best Of - 15 Massive AC/DC Rock Tributes","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-8.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7498a162ffefd1d5ab25e3d0686623a17ac4d1d45dc4a53bfb1b744208f6a771%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ecc74%2F0%2F55177%2F6a9d5198.42981995.8.16120911%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:23:07 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:23:07 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:07 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:23:07 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:07 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1474,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Highway to Hell","artist":"Masters of Rock","album":"AC/DC - The Best Of - 15 Massive AC/DC Rock Tributes","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-8.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7498a162ffefd1d5ab25e3d0686623a17ac4d1d45dc4a53bfb1b744208f6a771%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683ecc74%2F0%2F55177%2F6a9d5198.42981995.8.16120911%2F320.mp3&ext=.mp3","trackType":"mp3"} May 23 22:23:07 volumio volumio[821]: verbose: CURRENT POSITION 0 May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState stateService play May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play May 23 22:23:07 volumio volumio[821]: info: Received an update from plugin. extracting info from payload May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:07 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:07 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 31ms May 23 22:23:07 volumio volumio[821]: info: ------------------------------ 30ms May 23 22:23:07 volumio volumio[821]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 23 22:23:07 volumio volumio[821]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 23 22:23:08 volumio go-librespot[1189]: time="2025-05-23T22:23:08+03:00" level=trace msg="sent dealer ping" May 23 22:23:08 volumio go-librespot[1189]: time="2025-05-23T22:23:08+03:00" level=trace msg="received dealer pong" May 23 22:23:09 volumio volumio[821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 23 22:23:12 volumio volumio[821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 23 22:23:12 volumio volumio[821]: info: CoreCommandRouter::volumioGetState May 23 22:23:16 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:23:16 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 23 22:23:17 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::play index 3 May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock May 23 22:23:17 volumio volumio[821]: info: CorePlayQueue::getTrackBlock May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:17 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:17 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:23:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::serviceStop May 23 22:23:17 volumio volumio[821]: info: CorePlayQueue::getTrack 0 May 23 22:23:17 volumio volumio[821]: info: CoreCommandRouter::serviceStop May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:23:17 volumio volumio[821]: info: ControllerMpd::stop May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand stop took 6 milliseconds May 23 22:23:17 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:17 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:23:17 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:17 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand stop took 1 milliseconds May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand status took 0 milliseconds May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand clear took 1 milliseconds May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:17 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:23:17 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:17 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:17 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:23:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:17 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:17 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:23:17 volumio volumio[821]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 10ms May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 8ms May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:17 volumio volumio[821]: error: updateQueue error: null May 23 22:23:17 volumio volumio[821]: error: updateQueue error: null May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 9ms May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 1ms May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-148.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd43d4472eb739c43971518c75dcb8316f39c1c89283da02f4f9357f74edb5ca6%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683e9910%2F0%2F46881%2F407e89a5.25882683.5.16120921%2F320.mp3&ext=.mp3" May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-148.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd43d4472eb739c43971518c75dcb8316f39c1c89283da02f4f9357f74edb5ca6%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683e9910%2F0%2F46881%2F407e89a5.25882683.5.16120921%2F320.mp3&ext=.mp3" took 1 milliseconds May 23 22:23:17 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:23:17 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:17 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:23:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:23:17 volumio volumio[821]: info: May 23 22:23:17 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:17 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:23:17 volumio volumio[821]: info: sendMpdCommand play took 2 milliseconds May 23 22:23:17 volumio volumio[821]: info: ------------------------------ 1ms May 23 22:23:19 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::play index 3 May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::stop May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:19 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:23:19 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:23:19 volumio volumio[821]: info: May 23 22:23:19 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:19 volumio volumio[821]: info: sendMpdCommand stop took 2 milliseconds May 23 22:23:19 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:23:19 volumio volumio[821]: info: sendMpdCommand status took 1 milliseconds May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:19 volumio volumio[821]: info: May 23 22:23:19 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:19 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:19 volumio volumio[821]: info: May 23 22:23:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:19 volumio volumio[821]: info: sendMpdCommand clear took 4 milliseconds May 23 22:23:19 volumio volumio[821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:19 volumio volumio[821]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 22:23:19 volumio volumio[821]: info: ------------------------------ 5ms May 23 22:23:19 volumio volumio[821]: info: May 23 22:23:19 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:19 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:19 volumio volumio[821]: error: updateQueue error: null May 23 22:23:19 volumio volumio[821]: error: updateQueue error: null May 23 22:23:19 volumio volumio[821]: info: sendMpdCommand status took 7 milliseconds May 23 22:23:19 volumio volumio[821]: info: ------------------------------ 6ms May 23 22:23:19 volumio volumio[821]: info: ------------------------------ 1ms May 23 22:23:19 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:19 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:23:19 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:23:19 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:19 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:19 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:23:19 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:19 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:19 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:23:19 volumio volumio[821]: info: ------------------------------ 19ms May 23 22:23:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-139.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9d743a8060ab9a43244c1256492628c50e6da9aec020d33a9699ca47cbcc41d9%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683e9910%2F0%2F46881%2F407e89a5.25882683.5.16120921%2F320.mp3&ext=.mp3" May 23 22:23:20 volumio volumio[821]: info: May 23 22:23:20 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:20 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:20 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-139.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D9d743a8060ab9a43244c1256492628c50e6da9aec020d33a9699ca47cbcc41d9%2Clid%3D1583%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683e9910%2F0%2F46881%2F407e89a5.25882683.5.16120921%2F320.mp3&ext=.mp3" took 1 milliseconds May 23 22:23:20 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:23:20 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:23:20 volumio volumio[821]: info: May 23 22:23:20 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:20 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:20 volumio volumio[821]: info: May 23 22:23:20 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:20 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:20 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:23:20 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:20 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:23:20 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:23:20 volumio volumio[821]: info: May 23 22:23:20 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:20 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:20 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:20 volumio volumio[821]: info: sendMpdCommand play took 1 milliseconds May 23 22:23:20 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:22 volumio volumio[821]: info: CoreCommandRouter::volumioPlay May 23 22:23:22 volumio volumio[821]: info: CoreStateMachine::play index undefined May 23 22:23:22 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 22:23:22 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:22 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer May 23 22:23:22 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop May 23 22:23:22 volumio volumio[821]: info: May 23 22:23:22 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:22 volumio volumio[821]: info: sendMpdCommand stop took 2 milliseconds May 23 22:23:22 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear May 23 22:23:22 volumio volumio[821]: info: May 23 22:23:22 volumio volumio[821]: ---------------------------- MPD announces state update: player May 23 22:23:22 volumio volumio[821]: info: ControllerMpd::getState May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status May 23 22:23:22 volumio volumio[821]: info: May 23 22:23:22 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:22 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:22 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:23:22 volumio volumio[821]: info: sendMpdCommand clear took 2 milliseconds May 23 22:23:22 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::parseState May 23 22:23:22 volumio volumio[821]: info: ControllerMpd::pushState May 23 22:23:22 volumio volumio[821]: info: CoreCommandRouter::servicePushState May 23 22:23:22 volumio volumio[821]: info: CoreStateMachine::pushState May 23 22:23:22 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:22 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 22:23:22 volumio volumio[821]: info: CoreCommandRouter::volumioPushState May 23 22:23:22 volumio volumio[821]: info: CorePlayQueue::getTrack 3 May 23 22:23:22 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 23 22:23:22 volumio volumio[821]: info: ------------------------------ 8ms May 23 22:23:22 volumio volumio[821]: info: May 23 22:23:22 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:22 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:22 volumio volumio[821]: error: updateQueue error: null May 23 22:23:22 volumio volumio[821]: error: updateQueue error: null May 23 22:23:22 volumio volumio[821]: info: ------------------------------ 12ms May 23 22:23:22 volumio volumio[821]: info: sendMpdCommand playlistinfo took 11 milliseconds May 23 22:23:22 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:22 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo May 23 22:23:22 volumio volumio[821]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 23 22:23:22 volumio volumio[821]: info: ------------------------------ 16ms May 23 22:23:22 volumio kernel: TCP: out of memory -- consider tuning tcp_mem May 23 22:23:24 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-139.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1056103d919e2e5382b3d774772509f09987da8adf0604efdb695be79badf8a6%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683e9910%2F0%2F46881%2F407e89a5.25882683.5.16120921%2F320.mp3&ext=.mp3" May 23 22:23:24 volumio volumio[821]: info: May 23 22:23:24 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:24 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:24 volumio volumio[821]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-139.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1056103d919e2e5382b3d774772509f09987da8adf0604efdb695be79badf8a6%2Clid%3D1520%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683e9910%2F0%2F46881%2F407e89a5.25882683.5.16120921%2F320.mp3&ext=.mp3" took 1 milliseconds May 23 22:23:24 volumio volumio[821]: verbose: MPD COMMAND [object Object] May 23 22:23:24 volumio volumio[821]: info: May 23 22:23:24 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:24 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:24 volumio volumio[821]: info: May 23 22:23:24 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:24 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:24 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:23:24 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:24 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 22:23:24 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play May 23 22:23:24 volumio volumio[821]: info: May 23 22:23:24 volumio volumio[821]: ---------------------------- MPD announces system playlist update May 23 22:23:24 volumio volumio[821]: info: Ignoring MPD Status Update May 23 22:23:24 volumio volumio[821]: info: ------------------------------ 3ms May 23 22:23:24 volumio volumio[821]: info: sendMpdCommand play took 3 milliseconds May 23 22:23:24 volumio volumio[821]: info: ------------------------------ 2ms May 23 22:23:30 volumio volumio[821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 22:23:30 volumio volumio[821]: Error: read ECONNRESET May 23 22:23:30 volumio volumio[821]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { May 23 22:23:30 volumio volumio[821]: errno: -104, May 23 22:23:30 volumio volumio[821]: code: 'ECONNRESET', May 23 22:23:30 volumio volumio[821]: syscall: 'read' May 23 22:23:30 volumio volumio[821]: } May 23 22:23:30 volumio volumio[821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 22:23:30 volumio sudo[3317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 22:22 May 23 22:23:30 volumio sudo[3317]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"