-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sat 2026-05-30 19:40:12 MSK. -- May 30 19:39:03 volumio volumio[1145]: Searching all installed plugins May 30 19:39:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 19:39:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: , search May 30 19:39:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 30 19:39:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 30 19:39:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 30 19:39:03 volumio volumio[1145]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 30 19:39:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 30 19:39:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , search May 30 19:39:04 volumio volumio[1145]: Searching all installed plugins May 30 19:39:04 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 19:39:04 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: , search May 30 19:39:04 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 30 19:39:04 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 30 19:39:04 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 30 19:39:04 volumio volumio[1145]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 30 19:39:04 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 30 19:39:04 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , search May 30 19:39:06 volumio volumio[1145]: Searching all installed plugins May 30 19:39:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 19:39:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: , search May 30 19:39:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 30 19:39:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search May 30 19:39:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: last_100 , search May 30 19:39:06 volumio volumio[1145]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 May 30 19:39:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 30 19:39:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , search May 30 19:39:08 volumio volumio[1145]: error: Search in plugin webradio timed out May 30 19:39:08 volumio volumio[1145]: info: All search sources collected, pushing search results May 30 19:39:09 volumio volumio[1145]: error: Search in plugin webradio timed out May 30 19:39:09 volumio volumio[1145]: info: All search sources collected, pushing search results May 30 19:39:11 volumio volumio[1145]: error: Search in plugin webradio timed out May 30 19:39:11 volumio volumio[1145]: info: All search sources collected, pushing search results May 30 19:39:14 volumio volumio[1145]: info: CoreCommandRouter::volumioPause May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::pause May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::servicePause May 30 19:39:14 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:14 volumio volumio[1145]: info: CoreCommandRouter::servicePause May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:39:14 volumio volumio[1145]: info: ControllerMpd::pause May 30 19:39:14 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand pause May 30 19:39:14 volumio volumio[1145]: info: May 30 19:39:14 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:39:14 volumio volumio[1145]: info: sendMpdCommand pause took 1 milliseconds May 30 19:39:14 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:39:14 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:39:14 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:39:14 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:39:14 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:39:14 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 19:39:14 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:39:14 volumio volumio[1145]: info: ControllerMpd::pushState May 30 19:39:14 volumio volumio[1145]: info: CoreCommandRouter::servicePushState May 30 19:39:14 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:14 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":58643,"duration":206,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"1000%","artist":"Scorpions","album":"FREEDOM","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dbe56a6b8a5d94f82f2a27e61337880fac07efef81842cb88c3fc10bed972b71c%2Clid%3D62%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a285c52%2F32307%2F5232407%2F5191ce21.161699556.7.107470501%2F320.mp3&ext=.mp3","trackType":"mp3"} May 30 19:39:14 volumio volumio[1145]: verbose: CURRENT POSITION 0 May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus pause May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:39:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:39:14 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer May 30 19:39:14 volumio volumio[1145]: info: ------------------------------ 8ms May 30 19:39:14 volumio volumio[1145]: info: CoreCommandRouter::volumioGetQueue May 30 19:39:14 volumio volumio[1145]: info: CoreStateMachine::getQueue May 30 19:39:14 volumio volumio[1145]: info: CorePlayQueue::getQueue May 30 19:39:18 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 30 19:39:18 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 30 19:39:22 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:22 volumio volumio[1145]: info: Preloading song: yandex_music/track/141492914:39027099@39027099 May 30 19:39:22 volumio volumio[1145]: info: Preloading song: yandex_music/track/144805126:39027099@39027099 May 30 19:39:22 volumio volumio[1145]: info: Exploding uri yandex_music/track/141492914:39027099@39027099 in service yandex_music May 30 19:39:22 volumio volumio[1145]: info: Exploding uri yandex_music/track/144805126:39027099@39027099 in service yandex_music May 30 19:39:24 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::ClearQueue May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::serviceStop May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::serviceStop May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:39:24 volumio volumio[1145]: info: ControllerMpd::stop May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::clearPlayQueue May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:39:24 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:24 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141492914:39027099@39027099 May 30 19:39:24 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141492914:39027099@39027099 May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::play index 0 May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:39:24 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:24 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/144805126:39027099@39027099 May 30 19:39:24 volumio volumio[1145]: info: Using cached record of: yandex_music/track/144805126:39027099@39027099 May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::play index undefined May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer May 30 19:39:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:39:24 volumio volumio[1145]: info: May 30 19:39:24 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:39:24 volumio volumio[1145]: info: sendMpdCommand stop took 19 milliseconds May 30 19:39:24 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:39:24 volumio volumio[1145]: info: sendMpdCommand stop took 3 milliseconds May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear May 30 19:39:24 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:39:24 volumio volumio[1145]: info: May 30 19:39:24 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:39:24 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:39:24 volumio volumio[1145]: info: sendMpdCommand clear took 3 milliseconds May 30 19:39:24 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:39:24 volumio volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 30 19:39:24 volumio volumio[1145]: info: ------------------------------ 5ms May 30 19:39:24 volumio volumio[1145]: error: updateQueue error: null May 30 19:39:24 volumio volumio[1145]: info: ------------------------------ 5ms May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-158.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D71bab4d32573a2fd9d1a30c6c58c7d47d273a22ff394d8e535b6950b8ed076e9%2Clid%3D226%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28dba6%2F0%2F1668543%2Fa9216b10.210647679.6.141492914%2F320.mp3&ext=.mp3" May 30 19:39:24 volumio volumio[1145]: info: May 30 19:39:24 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:39:24 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:39:24 volumio volumio[1145]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-158.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D71bab4d32573a2fd9d1a30c6c58c7d47d273a22ff394d8e535b6950b8ed076e9%2Clid%3D226%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28dba6%2F0%2F1668543%2Fa9216b10.210647679.6.141492914%2F320.mp3&ext=.mp3" took 1 milliseconds May 30 19:39:24 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:39:24 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:39:24 volumio volumio[1145]: info: May 30 19:39:24 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:39:24 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:39:24 volumio volumio[1145]: info: ------------------------------ 1ms May 30 19:39:24 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:39:24 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play May 30 19:39:24 volumio volumio[1145]: info: ------------------------------ 1ms May 30 19:39:24 volumio volumio[1145]: info: sendMpdCommand play took 1 milliseconds May 30 19:39:25 volumio volumio[1145]: info: May 30 19:39:25 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:39:25 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:39:25 volumio volumio[1145]: info: May 30 19:39:25 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:39:25 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:39:25 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:39:25 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:39:25 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 19:39:25 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:39:25 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:39:25 volumio volumio[1145]: info: ControllerMpd::pushState May 30 19:39:25 volumio volumio[1145]: info: CoreCommandRouter::servicePushState May 30 19:39:25 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:25 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blackout","artist":"Scorpions","album":"Blackout","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-158.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D71bab4d32573a2fd9d1a30c6c58c7d47d273a22ff394d8e535b6950b8ed076e9%2Clid%3D226%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28dba6%2F0%2F1668543%2Fa9216b10.210647679.6.141492914%2F320.mp3&ext=.mp3","trackType":"mp3"} May 30 19:39:25 volumio volumio[1145]: verbose: CURRENT POSITION 0 May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop May 30 19:39:25 volumio volumio[1145]: info: ControllerMpd::pushState May 30 19:39:25 volumio volumio[1145]: info: CoreCommandRouter::servicePushState May 30 19:39:25 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:25 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blackout","artist":"Scorpions","album":"Blackout","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-158.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D71bab4d32573a2fd9d1a30c6c58c7d47d273a22ff394d8e535b6950b8ed076e9%2Clid%3D226%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28dba6%2F0%2F1668543%2Fa9216b10.210647679.6.141492914%2F320.mp3&ext=.mp3","trackType":"mp3"} May 30 19:39:25 volumio volumio[1145]: verbose: CURRENT POSITION 0 May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play May 30 19:39:25 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:39:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:39:25 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:39:25 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:39:25 volumio volumio[1145]: info: ------------------------------ 12ms May 30 19:39:25 volumio volumio[1145]: info: ------------------------------ 18ms May 30 19:39:25 volumio volumio[1145]: info: CoreCommandRouter::volumioGetQueue May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::getQueue May 30 19:39:25 volumio volumio[1145]: info: CorePlayQueue::getQueue May 30 19:39:25 volumio volumio[1145]: info: CoreCommandRouter::volumioGetQueue May 30 19:39:25 volumio volumio[1145]: info: CoreStateMachine::getQueue May 30 19:39:25 volumio volumio[1145]: info: CorePlayQueue::getQueue May 30 19:39:31 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 30 19:39:31 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442175:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442179:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442182:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442183:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442186:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442187:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442190:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442193:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442210:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442213:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442215:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442217:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442219:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442221:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442222:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442226:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Preloading song: yandex_music/track/141442228:37611559@37611559 May 30 19:39:31 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442175:37611559@37611559 in service yandex_music May 30 19:39:31 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442179:37611559@37611559 in service yandex_music May 30 19:39:31 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442182:37611559@37611559 in service yandex_music May 30 19:39:31 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442183:37611559@37611559 in service yandex_music May 30 19:39:31 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442186:37611559@37611559 in service yandex_music May 30 19:39:31 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442187:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442190:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442193:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442210:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442213:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442215:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442217:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442219:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442221:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442222:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442226:37611559@37611559 in service yandex_music May 30 19:39:32 volumio volumio[1145]: info: Exploding uri yandex_music/track/141442228:37611559@37611559 in service yandex_music May 30 19:39:47 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::ClearQueue May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::serviceStop May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::serviceStop May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:39:47 volumio volumio[1145]: info: ControllerMpd::stop May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::clearPlayQueue May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:39:47 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442175:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442175:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::play index 0 May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:39:47 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442179:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442179:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442182:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442182:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442183:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442183:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442186:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442186:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442187:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442187:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442190:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442190:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442193:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442193:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442210:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442210:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442213:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442213:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442215:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442215:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442217:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442217:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442219:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442219:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442221:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442221:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442222:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442222:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442226:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442226:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/141442228:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: Using cached record of: yandex_music/track/141442228:37611559@37611559 May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:47 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::play index undefined May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer May 30 19:39:47 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:39:47 volumio volumio[1145]: info: May 30 19:39:47 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:39:47 volumio volumio[1145]: info: sendMpdCommand stop took 46 milliseconds May 30 19:39:47 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:39:47 volumio volumio[1145]: info: sendMpdCommand stop took 15 milliseconds May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear May 30 19:39:47 volumio volumio[1145]: info: May 30 19:39:47 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:39:47 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:39:47 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:39:47 volumio volumio[1145]: info: sendMpdCommand clear took 0 milliseconds May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:39:47 volumio volumio[1145]: error: updateQueue error: null May 30 19:39:47 volumio volumio[1145]: info: ------------------------------ 5ms May 30 19:39:47 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:39:47 volumio volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 30 19:39:47 volumio volumio[1145]: info: ------------------------------ 7ms May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-81.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3708363c27784eb1cfd61d73effc2e3828c2875cc4142e9885355c6adca5d9a0%2Clid%3D62%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28da4a%2F0%2F109580%2Fafc81258.206393876.6.141442175%2F320.mp3&ext=.mp3" May 30 19:39:47 volumio volumio[1145]: info: May 30 19:39:47 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:39:47 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:39:47 volumio volumio[1145]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-81.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3708363c27784eb1cfd61d73effc2e3828c2875cc4142e9885355c6adca5d9a0%2Clid%3D62%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28da4a%2F0%2F109580%2Fafc81258.206393876.6.141442175%2F320.mp3&ext=.mp3" took 1 milliseconds May 30 19:39:47 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:39:47 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:39:47 volumio volumio[1145]: info: May 30 19:39:47 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:39:47 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:39:47 volumio volumio[1145]: info: ------------------------------ 1ms May 30 19:39:47 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:39:47 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play May 30 19:39:47 volumio volumio[1145]: info: ------------------------------ 3ms May 30 19:39:47 volumio volumio[1145]: info: sendMpdCommand play took 3 milliseconds May 30 19:39:48 volumio volumio[1145]: info: May 30 19:39:48 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:39:48 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:39:48 volumio volumio[1145]: info: May 30 19:39:48 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:39:48 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:39:48 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:39:48 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:39:48 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 19:39:48 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:39:48 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:39:48 volumio volumio[1145]: info: ControllerMpd::pushState May 30 19:39:48 volumio volumio[1145]: info: CoreCommandRouter::servicePushState May 30 19:39:48 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:48 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock You Like A Hurricane","artist":"Scorpions","album":"El Paso 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-81.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3708363c27784eb1cfd61d73effc2e3828c2875cc4142e9885355c6adca5d9a0%2Clid%3D62%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28da4a%2F0%2F109580%2Fafc81258.206393876.6.141442175%2F320.mp3&ext=.mp3","trackType":"mp3"} May 30 19:39:48 volumio volumio[1145]: verbose: CURRENT POSITION 0 May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop May 30 19:39:48 volumio volumio[1145]: info: ControllerMpd::pushState May 30 19:39:48 volumio volumio[1145]: info: CoreCommandRouter::servicePushState May 30 19:39:48 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:39:48 volumio volumio[1145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock You Like A Hurricane","artist":"Scorpions","album":"El Paso 1996","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-81.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3708363c27784eb1cfd61d73effc2e3828c2875cc4142e9885355c6adca5d9a0%2Clid%3D62%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28da4a%2F0%2F109580%2Fafc81258.206393876.6.141442175%2F320.mp3&ext=.mp3","trackType":"mp3"} May 30 19:39:48 volumio volumio[1145]: verbose: CURRENT POSITION 0 May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::syncState stateService play May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus play May 30 19:39:48 volumio volumio[1145]: info: Received an update from plugin. extracting info from payload May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:39:48 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:39:48 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:39:48 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:39:48 volumio volumio[1145]: info: ------------------------------ 11ms May 30 19:39:48 volumio volumio[1145]: info: ------------------------------ 16ms May 30 19:39:48 volumio volumio[1145]: info: CoreCommandRouter::volumioGetQueue May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::getQueue May 30 19:39:48 volumio volumio[1145]: info: CorePlayQueue::getQueue May 30 19:39:48 volumio volumio[1145]: info: CoreCommandRouter::volumioGetQueue May 30 19:39:48 volumio volumio[1145]: info: CoreStateMachine::getQueue May 30 19:39:48 volumio volumio[1145]: info: CorePlayQueue::getQueue May 30 19:39:55 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 30 19:39:55 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:57 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri May 30 19:39:58 volumio volumio[1145]: info: Preload queue cleared May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793827:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793828:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793829:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793830:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793831:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793832:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793834:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793835:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793836:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793837:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793838:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793839:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793840:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793841:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793842:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793843:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793844:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Preloading song: yandex_music/track/139793845:36900791@36900791 May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793827:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793828:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793829:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793830:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793831:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793832:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793834:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793835:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793836:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793837:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793838:36900791@36900791 in service yandex_music May 30 19:39:58 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793839:36900791@36900791 in service yandex_music May 30 19:39:59 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793840:36900791@36900791 in service yandex_music May 30 19:39:59 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793841:36900791@36900791 in service yandex_music May 30 19:39:59 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793842:36900791@36900791 in service yandex_music May 30 19:39:59 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793843:36900791@36900791 in service yandex_music May 30 19:39:59 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793844:36900791@36900791 in service yandex_music May 30 19:39:59 volumio volumio[1145]: info: Exploding uri yandex_music/track/139793845:36900791@36900791 in service yandex_music May 30 19:40:00 volumio volumio[1145]: info: Preload queue cleared May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::ClearQueue May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::stPlaybackTimer May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::pushState May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::serviceStop May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::serviceStop May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:40:00 volumio volumio[1145]: info: ControllerMpd::stop May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::clearPlayQueue May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:40:00 volumio volumio[1145]: info: Preload queue cleared May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793827:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793827:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::play index 0 May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:40:00 volumio volumio[1145]: info: Preload queue cleared May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793828:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793828:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793829:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793829:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793830:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793830:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793831:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793831:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793832:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793832:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793834:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793834:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793835:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793835:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793836:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793836:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793837:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793837:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793838:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793838:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793839:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793839:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793840:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793840:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793841:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793841:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793842:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793842:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793843:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793843:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793844:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793844:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793845:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793845:36900791@36900791 May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:00 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::play index undefined May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer May 30 19:40:00 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:40:00 volumio volumio[1145]: info: sendMpdCommand stop took 29 milliseconds May 30 19:40:00 volumio volumio[1145]: info: May 30 19:40:00 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:40:00 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:40:00 volumio volumio[1145]: info: sendMpdCommand stop took 10 milliseconds May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear May 30 19:40:00 volumio volumio[1145]: info: May 30 19:40:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:00 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:00 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:40:00 volumio volumio[1145]: info: sendMpdCommand clear took 0 milliseconds May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:40:00 volumio volumio[1145]: error: updateQueue error: null May 30 19:40:00 volumio volumio[1145]: info: ------------------------------ 4ms May 30 19:40:00 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:40:00 volumio volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 30 19:40:00 volumio volumio[1145]: info: ------------------------------ 6ms May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-110.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D46ba1745e55bf03ed5164384a916723ac541b3213fd8304799fb7552d0f3c9a2%2Clid%3D63%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28c45f%2F0%2F4471198%2F3cce158d.204330684.6.139793827%2F320.mp3&ext=.mp3" May 30 19:40:00 volumio volumio[1145]: info: May 30 19:40:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:00 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:00 volumio volumio[1145]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-110.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D46ba1745e55bf03ed5164384a916723ac541b3213fd8304799fb7552d0f3c9a2%2Clid%3D63%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a28c45f%2F0%2F4471198%2F3cce158d.204330684.6.139793827%2F320.mp3&ext=.mp3" took 1 milliseconds May 30 19:40:00 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:40:00 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:40:00 volumio volumio[1145]: info: May 30 19:40:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:00 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:00 volumio volumio[1145]: info: ------------------------------ 1ms May 30 19:40:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:40:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play May 30 19:40:00 volumio volumio[1145]: info: ------------------------------ 1ms May 30 19:40:00 volumio volumio[1145]: info: sendMpdCommand play took 1 milliseconds May 30 19:40:04 volumio volumio[1145]: info: Preload queue cleared May 30 19:40:04 volumio volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::ClearQueue May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::clearPlayQueue May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:40:04 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:40:04 volumio volumio[1145]: info: Preload queue cleared May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793827:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793827:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793828:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793828:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:40:04 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::play index 1 May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::addQueueItems May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::addQueueItems May 30 19:40:04 volumio volumio[1145]: info: Preload queue cleared May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793829:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793829:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793830:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793830:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793831:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793831:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793832:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793832:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793834:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793834:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793835:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793835:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793836:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793836:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793837:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793837:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793838:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793838:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793839:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793839:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793840:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793840:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793841:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793841:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793842:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793842:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793843:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793843:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793844:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793844:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Adding Item to queue: yandex_music/track/139793845:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: Using cached record of: yandex_music/track/139793845:36900791@36900791 May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::stop May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:04 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::saveQueue May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::play index undefined May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::getTrack 1 May 30 19:40:04 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer May 30 19:40:04 volumio volumio[1145]: info: CorePlayQueue::getTrack 1 May 30 19:40:04 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:40:04 volumio volumio[1145]: info: May 30 19:40:04 volumio volumio[1145]: ---------------------------- MPD announces state update: player May 30 19:40:04 volumio volumio[1145]: info: sendMpdCommand stop took 2 milliseconds May 30 19:40:04 volumio volumio[1145]: info: ControllerMpd::getState May 30 19:40:04 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status May 30 19:40:04 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear May 30 19:40:04 volumio volumio[1145]: info: May 30 19:40:04 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:04 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:04 volumio volumio[1145]: info: sendMpdCommand status took 1 milliseconds May 30 19:40:04 volumio volumio[1145]: info: sendMpdCommand clear took 1 milliseconds May 30 19:40:04 volumio volumio[1145]: verbose: ControllerMpd::parseState May 30 19:40:04 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 19:40:04 volumio volumio[1145]: error: updateQueue error: null May 30 19:40:04 volumio volumio[1145]: info: ------------------------------ 4ms May 30 19:40:04 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 19:40:04 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo May 30 19:40:04 volumio volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 30 19:40:04 volumio volumio[1145]: info: ------------------------------ 7ms May 30 19:40:05 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay May 30 19:40:05 volumio volumio[1145]: info: CoreStateMachine::play index undefined May 30 19:40:05 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 19:40:05 volumio volumio[1145]: info: CorePlayQueue::getTrack 1 May 30 19:40:05 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer May 30 19:40:05 volumio volumio[1145]: info: CorePlayQueue::getTrack 1 May 30 19:40:05 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop May 30 19:40:05 volumio volumio[1145]: info: sendMpdCommand stop took 1 milliseconds May 30 19:40:05 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear May 30 19:40:05 volumio volumio[1145]: info: May 30 19:40:05 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:05 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:05 volumio volumio[1145]: info: sendMpdCommand clear took 1 milliseconds May 30 19:40:05 volumio volumio[1145]: error: updateQueue error: null May 30 19:40:05 volumio volumio[1145]: info: ------------------------------ 4ms May 30 19:40:06 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-190.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7b8ef27f567c8b7d40d333ad971dc73f76a43ffa4a264f7fef8e43d45f0552a4%2Clid%3D64%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a296bdf%2F0%2F122164%2F67c5407d.204330685.6.139793828%2F320.mp3&ext=.mp3" May 30 19:40:06 volumio volumio[1145]: info: May 30 19:40:06 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:06 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:06 volumio volumio[1145]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-190.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7b8ef27f567c8b7d40d333ad971dc73f76a43ffa4a264f7fef8e43d45f0552a4%2Clid%3D64%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a296bdf%2F0%2F122164%2F67c5407d.204330685.6.139793828%2F320.mp3&ext=.mp3" took 1 milliseconds May 30 19:40:06 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:40:06 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:40:06 volumio volumio[1145]: info: May 30 19:40:06 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:06 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:06 volumio volumio[1145]: info: ------------------------------ 2ms May 30 19:40:06 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:40:06 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play May 30 19:40:06 volumio volumio[1145]: info: ------------------------------ 2ms May 30 19:40:06 volumio volumio[1145]: info: sendMpdCommand play took 1 milliseconds May 30 19:40:07 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-53.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc28fe06e936ce58253dc7af7459f90dc87aebe0fcc243e52c7361b5c123d9cea%2Clid%3D63%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a296bdf%2F0%2F122164%2F67c5407d.204330685.6.139793828%2F320.mp3&ext=.mp3" May 30 19:40:07 volumio volumio[1145]: info: May 30 19:40:07 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:07 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:07 volumio volumio[1145]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-53.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc28fe06e936ce58253dc7af7459f90dc87aebe0fcc243e52c7361b5c123d9cea%2Clid%3D63%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a296bdf%2F0%2F122164%2F67c5407d.204330685.6.139793828%2F320.mp3&ext=.mp3" took 1 milliseconds May 30 19:40:07 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:40:07 volumio volumio[1145]: verbose: MPD COMMAND [object Object] May 30 19:40:07 volumio volumio[1145]: info: May 30 19:40:07 volumio volumio[1145]: ---------------------------- MPD announces system playlist update May 30 19:40:07 volumio volumio[1145]: info: Ignoring MPD Status Update May 30 19:40:07 volumio volumio[1145]: info: ------------------------------ 2ms May 30 19:40:07 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 19:40:07 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play May 30 19:40:07 volumio volumio[1145]: info: ------------------------------ 2ms May 30 19:40:07 volumio volumio[1145]: info: sendMpdCommand play took 1 milliseconds May 30 19:40:12 volumio volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 19:40:12 volumio volumio[1145]: Error: read ECONNRESET May 30 19:40:12 volumio volumio[1145]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { May 30 19:40:12 volumio volumio[1145]: errno: -104, May 30 19:40:12 volumio volumio[1145]: code: 'ECONNRESET', May 30 19:40:12 volumio volumio[1145]: syscall: 'read' May 30 19:40:12 volumio volumio[1145]: } May 30 19:40:12 volumio volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 19:40:12 volumio sudo[8534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-30 19:39 May 30 19:40:12 volumio sudo[8534]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"