-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Wed 2026-08-26 19:28:19 MSK. -- Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getTrack 18 Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:27:57 volumio volumio[987]: info: Prefetching next song Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac" Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:27:57 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac" took 2 milliseconds Aug 26 19:27:57 volumio volumio[987]: verbose: MPD COMMAND [object Object] Aug 26 19:27:57 volumio volumio[987]: verbose: MPD COMMAND [object Object] Aug 26 19:27:57 volumio volumio[987]: verbose: MPD COMMAND [object Object] Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:27:57 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:27:57 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:27:57 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 5ms Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 3ms Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 2ms Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:27:57 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:27:57 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces state update: options Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 5ms Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand consume 1 took 3 milliseconds Aug 26 19:27:57 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 4ms Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 3ms Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces state update: options Aug 26 19:27:57 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:27:57 volumio volumio[987]: info: Aug 26 19:27:57 volumio volumio[987]: ---------------------------- MPD announces state update: options Aug 26 19:27:57 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand status took 3 milliseconds Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand status took 3 milliseconds Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand status took 2 milliseconds Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 19:27:57 volumio volumio[987]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 19:27:57 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:27:57 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getTrack 18 Aug 26 19:27:57 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234880,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Another Brick In The Wall, Part 2","artist":"Pink Floyd","album":"The Discovery Boxset","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-138.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dce57f4542a1ee0cd95cfe0424ebf77eae6c0c6f36f1e41d370654988eeb0e02b%2Clid%3D100%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d451b%2F0%2F43387%2F325a6ac7.140848199.5.2774891%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:27:57 volumio volumio[987]: verbose: CURRENT POSITION 18 Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:27:57 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:27:57 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getTrack 18 Aug 26 19:27:57 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234880,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Another Brick In The Wall, Part 2","artist":"Pink Floyd","album":"The Discovery Boxset","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-138.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dce57f4542a1ee0cd95cfe0424ebf77eae6c0c6f36f1e41d370654988eeb0e02b%2Clid%3D100%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d451b%2F0%2F43387%2F325a6ac7.140848199.5.2774891%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:27:57 volumio volumio[987]: verbose: CURRENT POSITION 18 Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:27:57 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:27:57 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getTrack 18 Aug 26 19:27:57 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234880,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Another Brick In The Wall, Part 2","artist":"Pink Floyd","album":"The Discovery Boxset","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-138.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dce57f4542a1ee0cd95cfe0424ebf77eae6c0c6f36f1e41d370654988eeb0e02b%2Clid%3D100%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d451b%2F0%2F43387%2F325a6ac7.140848199.5.2774891%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:27:57 volumio volumio[987]: verbose: CURRENT POSITION 18 Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:27:57 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 65ms Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 64ms Aug 26 19:27:57 volumio volumio[987]: info: ------------------------------ 64ms Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:27:57 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:27:57 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:01 volumio volumio[987]: info: Aug 26 19:28:01 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:01 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:01 volumio volumio[987]: info: Aug 26 19:28:01 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:01 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:01 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:01 volumio volumio[987]: info: Aug 26 19:28:01 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:01 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:01 volumio volumio[987]: info: Aug 26 19:28:01 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:01 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:01 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:01 volumio volumio[987]: info: Aug 26 19:28:01 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:01 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:01 volumio volumio[987]: info: Aug 26 19:28:01 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:01 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:01 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:01 volumio volumio[987]: info: ------------------------------ 7ms Aug 26 19:28:01 volumio volumio[987]: info: CoreStateMachine::startPlaybackTimer Aug 26 19:28:01 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:01 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:01 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:01 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:01 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:01 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 19:28:02 volumio volumio[987]: info: Aug 26 19:28:02 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand status took 987 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 986ms Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand status took 987 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 986ms Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand status took 985 milliseconds Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:02 volumio volumio[987]: info: Aug 26 19:28:02 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:02 volumio volumio[987]: info: Aug 26 19:28:02 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand status took 20 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand status took 18 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand status took 17 milliseconds Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":334,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1079 Kbps","isStreaming":false,"title":"..and Death Was Nothing to Him","artist":"Cypecore","album":"Innocent","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac","trackType":"flac"} Aug 26 19:28:02 volumio volumio[987]: verbose: CURRENT POSITION 19 Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:02 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":334,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1079 Kbps","isStreaming":false,"title":"..and Death Was Nothing to Him","artist":"Cypecore","album":"Innocent","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac","trackType":"flac"} Aug 26 19:28:02 volumio volumio[987]: verbose: CURRENT POSITION 19 Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:02 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":334,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1079 Kbps","isStreaming":false,"title":"..and Death Was Nothing to Him","artist":"Cypecore","album":"Innocent","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac","trackType":"flac"} Aug 26 19:28:02 volumio volumio[987]: verbose: CURRENT POSITION 19 Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:02 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 1080ms Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 1078ms Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 1077ms Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand playlistinfo took 155 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand playlistinfo took 149 milliseconds Aug 26 19:28:02 volumio volumio[987]: info: sendMpdCommand playlistinfo took 149 milliseconds Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:02 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":334,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1079 Kbps","isStreaming":false,"title":"..and Death Was Nothing to Him","artist":"Cypecore","album":"Innocent","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac","trackType":"flac"} Aug 26 19:28:02 volumio volumio[987]: verbose: CURRENT POSITION 19 Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:02 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":334,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1079 Kbps","isStreaming":false,"title":"..and Death Was Nothing to Him","artist":"Cypecore","album":"Innocent","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac","trackType":"flac"} Aug 26 19:28:02 volumio volumio[987]: verbose: CURRENT POSITION 19 Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:02 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":334,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1079 Kbps","isStreaming":false,"title":"..and Death Was Nothing to Him","artist":"Cypecore","album":"Innocent","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-67.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd413a5136e904f17d7a47f1fa26c4383231301d99b29c7ad46c510f39615014b%2Clid%3D88%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d57de%2F0%2F5284665%2Fa3f5ee04.158020949.22.104804908%2Fflac-mp4&ext=.flac","trackType":"flac"} Aug 26 19:28:02 volumio volumio[987]: verbose: CURRENT POSITION 19 Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:02 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 230ms Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 227ms Aug 26 19:28:02 volumio volumio[987]: info: ------------------------------ 227ms Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:02 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:02 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPlay Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::play index 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::stop Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrackBlock Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::serviceStop Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 19 Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::serviceStop Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::stop Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand stop took 29 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::play index undefined Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::startPlaybackTimer Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 19 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand stop took 18 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 16 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 13 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand clear took 3 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 49ms Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 26 19:28:04 volumio volumio[987]: error: updateQueue error: null Aug 26 19:28:04 volumio volumio[987]: error: updateQueue error: null Aug 26 19:28:04 volumio volumio[987]: error: updateQueue error: null Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 34 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 33ms Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 4ms Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 26 19:28:04 volumio volumio[987]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 63ms Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 62ms Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3" Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3" took 2 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: MPD COMMAND [object Object] Aug 26 19:28:04 volumio volumio[987]: verbose: MPD COMMAND [object Object] Aug 26 19:28:04 volumio volumio[987]: verbose: MPD COMMAND [object Object] Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 4ms Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 3ms Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 2ms Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand play Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces system playlist update Aug 26 19:28:04 volumio volumio[987]: info: Ignoring MPD Status Update Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 4ms Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand play took 3 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 2ms Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 1ms Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 17 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 18 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 17 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Safe in New York City","artist":"AC/DC","album":"Stiff Upper Lip","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:28:04 volumio volumio[987]: verbose: CURRENT POSITION 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus stop Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 25ms Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: Aug 26 19:28:04 volumio volumio[987]: ---------------------------- MPD announces state update: player Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::getState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 9 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":777,"duration":238,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Safe in New York City","artist":"AC/DC","album":"Stiff Upper Lip","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:28:04 volumio volumio[987]: verbose: CURRENT POSITION 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:04 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Safe in New York City","artist":"AC/DC","album":"Stiff Upper Lip","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:28:04 volumio volumio[987]: verbose: CURRENT POSITION 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:04 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 86ms Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 87ms Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 124 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand status took 124 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 122 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseState Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Safe in New York City","artist":"AC/DC","album":"Stiff Upper Lip","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:28:04 volumio volumio[987]: verbose: CURRENT POSITION 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:04 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 160ms Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 44 milliseconds Aug 26 19:28:04 volumio volumio[987]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Safe in New York City","artist":"AC/DC","album":"Stiff Upper Lip","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:28:04 volumio volumio[987]: verbose: CURRENT POSITION 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:04 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: ControllerMpd::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::servicePushState Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getTrack 16 Aug 26 19:28:04 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":238,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Safe in New York City","artist":"AC/DC","album":"Stiff Upper Lip","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-135.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D70fe749ddb8e7b6d14a806f383afb1767b746f234522fea5435b4cef2abdcba9%2Clid%3D101%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6a9d78b2%2F0%2F915229%2Fu%2F02f9071b-b20c-4788-a998-e9e0899c82d6.1%2F320.mp3&ext=.mp3","trackType":"mp3"} Aug 26 19:28:04 volumio volumio[987]: verbose: CURRENT POSITION 16 Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:28:04 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::pushState Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 205ms Aug 26 19:28:04 volumio volumio[987]: info: ------------------------------ 205ms Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:28:04 volumio volumio[987]: info: CoreStateMachine::getQueue Aug 26 19:28:04 volumio volumio[987]: info: CorePlayQueue::getQueue Aug 26 19:28:18 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 19:28:18 volumio volumio[987]: Error: read ECONNRESET Aug 26 19:28:18 volumio volumio[987]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Aug 26 19:28:18 volumio volumio[987]: errno: -104, Aug 26 19:28:18 volumio volumio[987]: code: 'ECONNRESET', Aug 26 19:28:18 volumio volumio[987]: syscall: 'read' Aug 26 19:28:18 volumio volumio[987]: } Aug 26 19:28:18 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 19:28:19 volumio sudo[3238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-26 19:27 Aug 26 19:28:19 volumio sudo[3238]: 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"