-- Logs begin at Thu 2019-02-14 07:11:58 -03, end at Fri 2025-05-16 16:15:47 -03. -- May 16 16:14:00 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 16:14:00 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 16:14:00 volumio volumio[1010]: info: Discovery: Getting this device information May 16 16:14:00 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:14:00 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 16:14:03 volumio volumio[1010]: verbose: New Socket.io Connection to 192.168.0.145:3000 from 192.168.0.201 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 16 16:14:17 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 16:14:17 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 16:14:17 volumio volumio[1010]: info: Discovery: Getting this device information May 16 16:14:17 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:14:17 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 16:14:19 volumio volumio[1010]: verbose: New Socket.io Connection to 192.168.0.145:3000 from 192.168.0.201 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 16 16:14:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 16:14:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 16:14:23 volumio volumio[1010]: info: Discovery: Getting this device information May 16 16:14:23 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:14:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:14:28 volumio volumio[1010]: info: ControllerMpd::next May 16 16:14:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:14:28 volumio volumio[1010]: info: May 16 16:14:28 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:28 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:28 volumio volumio[1010]: info: May 16 16:14:28 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:28 volumio volumio[1010]: info: sendMpdCommand next took 8 milliseconds May 16 16:14:28 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:28 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:28 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:28 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:28 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:28 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 150 May 16 16:14:28 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:14:28 volumio volumio[1010]: verbose: CURRENT POSITION 150 May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 151 May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 151 May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:14:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 151 May 16 16:14:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:14:28 volumio volumio[1010]: info: ------------------------------ 16ms May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 151 May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:28 volumio volumio[1010]: info: sendMpdCommand stop took 13 milliseconds May 16 16:14:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:14:28 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:28 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:28 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:28 volumio volumio[1010]: info: May 16 16:14:28 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:28 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:28 volumio volumio[1010]: info: sendMpdCommand clear took 21 milliseconds May 16 16:14:29 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:29 volumio volumio[1010]: info: ------------------------------ 33ms May 16 16:14:29 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-96.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc8155fa9144a4bacd2cf9feec416ed9bd600b5704ee2245cea7d82efb4fff126%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68353dd5%2F0%2F219226%2F5b531a56.57460770.5.38397647%2F320.mp3&ext=.mp3" May 16 16:14:29 volumio volumio[1010]: info: May 16 16:14:29 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:29 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:29 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-96.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc8155fa9144a4bacd2cf9feec416ed9bd600b5704ee2245cea7d82efb4fff126%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68353dd5%2F0%2F219226%2F5b531a56.57460770.5.38397647%2F320.mp3&ext=.mp3" took 3 milliseconds May 16 16:14:29 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:29 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:29 volumio volumio[1010]: info: May 16 16:14:29 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:29 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:29 volumio volumio[1010]: info: ------------------------------ 4ms May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:14:29 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:29 volumio volumio[1010]: info: sendMpdCommand play took 1 milliseconds May 16 16:14:29 volumio volumio[1010]: info: May 16 16:14:29 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:29 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:29 volumio volumio[1010]: info: May 16 16:14:29 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:29 volumio volumio[1010]: info: sendMpdCommand status took 24 milliseconds May 16 16:14:29 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:29 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:29 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:29 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:29 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:29 volumio volumio[1010]: info: CorePlayQueue::getTrack 151 May 16 16:14:29 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Un-Break My Heart","artist":"Toni Braxton","album":"Ladies & The City","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-96.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc8155fa9144a4bacd2cf9feec416ed9bd600b5704ee2245cea7d82efb4fff126%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68353dd5%2F0%2F219226%2F5b531a56.57460770.5.38397647%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:29 volumio volumio[1010]: verbose: CURRENT POSITION 151 May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:14:29 volumio volumio[1010]: info: ------------------------------ 28ms May 16 16:14:29 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 16:14:29 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:29 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:29 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:29 volumio volumio[1010]: info: CorePlayQueue::getTrack 151 May 16 16:14:29 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":273,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Un-Break My Heart","artist":"Toni Braxton","album":"Ladies & The City","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-96.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc8155fa9144a4bacd2cf9feec416ed9bd600b5704ee2245cea7d82efb4fff126%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68353dd5%2F0%2F219226%2F5b531a56.57460770.5.38397647%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:29 volumio volumio[1010]: verbose: CURRENT POSITION 151 May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:29 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:29 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:29 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:29 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:29 volumio volumio[1010]: info: ------------------------------ 21ms May 16 16:14:29 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:29 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:29 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:29 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:29 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:29 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:14:34 volumio volumio[1010]: info: ControllerMpd::next May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:14:34 volumio volumio[1010]: info: May 16 16:14:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:34 volumio volumio[1010]: info: May 16 16:14:34 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand next took 18 milliseconds May 16 16:14:34 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:34 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:34 volumio volumio[1010]: info: ------------------------------ 1ms May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:34 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 151 May 16 16:14:34 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:14:34 volumio volumio[1010]: verbose: CURRENT POSITION 151 May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 152 May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 152 May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 152 May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:14:34 volumio volumio[1010]: info: ------------------------------ 12ms May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 152 May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand stop took 10 milliseconds May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:34 volumio volumio[1010]: info: May 16 16:14:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand clear took 17 milliseconds May 16 16:14:34 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:34 volumio volumio[1010]: info: ------------------------------ 33ms May 16 16:14:34 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-73.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De803c03919ff7fe4f26a4654ff5c4110f4097a87e53f7371b9835c29d003417a%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683552bc%2F38835%2F48156%2F2a291a42.907829.3.76115%2F320.mp3&ext=.mp3" May 16 16:14:34 volumio volumio[1010]: info: May 16 16:14:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-73.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De803c03919ff7fe4f26a4654ff5c4110f4097a87e53f7371b9835c29d003417a%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683552bc%2F38835%2F48156%2F2a291a42.907829.3.76115%2F320.mp3&ext=.mp3" took 2 milliseconds May 16 16:14:34 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:34 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:34 volumio volumio[1010]: info: May 16 16:14:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:34 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:14:34 volumio volumio[1010]: info: ------------------------------ 1ms May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand play took 1 milliseconds May 16 16:14:34 volumio volumio[1010]: info: May 16 16:14:34 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:34 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand status took 17 milliseconds May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:34 volumio volumio[1010]: info: May 16 16:14:34 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:34 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:34 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 152 May 16 16:14:34 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":249,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Never, Never Gonna Give Ya Up","artist":"Barry White","album":"The Complete 20th Century Records Singles (1973-1979)","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-73.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De803c03919ff7fe4f26a4654ff5c4110f4097a87e53f7371b9835c29d003417a%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683552bc%2F38835%2F48156%2F2a291a42.907829.3.76115%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:34 volumio volumio[1010]: verbose: CURRENT POSITION 152 May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:14:34 volumio volumio[1010]: info: ------------------------------ 23ms May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand status took 2 milliseconds May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:34 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:34 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:34 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 152 May 16 16:14:34 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":249,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Never, Never Gonna Give Ya Up","artist":"Barry White","album":"The Complete 20th Century Records Singles (1973-1979)","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-73.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De803c03919ff7fe4f26a4654ff5c4110f4097a87e53f7371b9835c29d003417a%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683552bc%2F38835%2F48156%2F2a291a42.907829.3.76115%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:34 volumio volumio[1010]: verbose: CURRENT POSITION 152 May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:34 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:34 volumio volumio[1010]: info: ------------------------------ 18ms May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:34 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:34 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:34 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:34 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:14:34 volumio volumio[1010]: verbose: New Socket.io Connection to 192.168.0.145:3000 from 192.168.0.201 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:14:37 volumio volumio[1010]: info: ControllerMpd::next May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:14:37 volumio volumio[1010]: info: May 16 16:14:37 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:37 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:37 volumio volumio[1010]: info: May 16 16:14:37 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand next took 14 milliseconds May 16 16:14:37 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:37 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:37 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:37 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 152 May 16 16:14:37 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:14:37 volumio volumio[1010]: verbose: CURRENT POSITION 152 May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 153 May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 153 May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 153 May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:14:37 volumio volumio[1010]: info: ------------------------------ 16ms May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 153 May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand stop took 12 milliseconds May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:37 volumio volumio[1010]: info: May 16 16:14:37 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:37 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand clear took 32 milliseconds May 16 16:14:37 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:37 volumio volumio[1010]: info: ------------------------------ 35ms May 16 16:14:37 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-137.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De898433f214b1d18877d8953329b262cf46897aee50db3fee1b274198362dd66%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68358408%2F0%2F937646%2F8995dc95.192493254.7.31499205%2F320.mp3&ext=.mp3" May 16 16:14:37 volumio volumio[1010]: info: May 16 16:14:37 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:37 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-137.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De898433f214b1d18877d8953329b262cf46897aee50db3fee1b274198362dd66%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68358408%2F0%2F937646%2F8995dc95.192493254.7.31499205%2F320.mp3&ext=.mp3" took 1 milliseconds May 16 16:14:37 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:37 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:37 volumio volumio[1010]: info: May 16 16:14:37 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:37 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:37 volumio volumio[1010]: info: ------------------------------ 3ms May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:14:37 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand play took 2 milliseconds May 16 16:14:37 volumio volumio[1010]: info: May 16 16:14:37 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:37 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:37 volumio volumio[1010]: info: May 16 16:14:37 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand status took 23 milliseconds May 16 16:14:37 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:37 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 153 May 16 16:14:37 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"True Colors","artist":"Phil Collins","album":"The Singles","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-137.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De898433f214b1d18877d8953329b262cf46897aee50db3fee1b274198362dd66%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68358408%2F0%2F937646%2F8995dc95.192493254.7.31499205%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:37 volumio volumio[1010]: verbose: CURRENT POSITION 153 May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:14:37 volumio volumio[1010]: info: ------------------------------ 27ms May 16 16:14:37 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:37 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:37 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 153 May 16 16:14:37 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":274,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"True Colors","artist":"Phil Collins","album":"The Singles","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-137.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De898433f214b1d18877d8953329b262cf46897aee50db3fee1b274198362dd66%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68358408%2F0%2F937646%2F8995dc95.192493254.7.31499205%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:37 volumio volumio[1010]: verbose: CURRENT POSITION 153 May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:37 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:37 volumio volumio[1010]: info: ------------------------------ 17ms May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:37 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:37 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:37 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:37 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:14:41 volumio volumio[1010]: info: ControllerMpd::next May 16 16:14:41 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:14:41 volumio volumio[1010]: info: May 16 16:14:41 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:41 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:41 volumio volumio[1010]: info: May 16 16:14:41 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:41 volumio volumio[1010]: info: sendMpdCommand next took 19 milliseconds May 16 16:14:41 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:41 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:41 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:41 volumio volumio[1010]: info: ------------------------------ 5ms May 16 16:14:41 volumio volumio[1010]: info: sendMpdCommand status took 4 milliseconds May 16 16:14:41 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:41 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:41 volumio volumio[1010]: info: CorePlayQueue::getTrack 153 May 16 16:14:41 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:14:41 volumio volumio[1010]: verbose: CURRENT POSITION 153 May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:41 volumio volumio[1010]: info: CorePlayQueue::getTrack 154 May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:41 volumio volumio[1010]: info: CorePlayQueue::getTrack 154 May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:14:41 volumio volumio[1010]: info: CorePlayQueue::getTrack 154 May 16 16:14:41 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:14:41 volumio volumio[1010]: info: ------------------------------ 16ms May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:41 volumio volumio[1010]: info: CorePlayQueue::getTrack 154 May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:41 volumio volumio[1010]: info: sendMpdCommand stop took 8 milliseconds May 16 16:14:41 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:14:41 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:41 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:41 volumio volumio[1010]: info: May 16 16:14:41 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:41 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:41 volumio volumio[1010]: info: sendMpdCommand clear took 19 milliseconds May 16 16:14:41 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:41 volumio volumio[1010]: info: ------------------------------ 37ms May 16 16:14:41 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:14:41 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-122.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D145047572fba4e4beef4c26df327ea1e9f12b793f897454cb23c459a640edc80%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835ddae%2F0%2F1464465%2Faefe763d.195913601.11.122021%2F320.mp3&ext=.mp3" May 16 16:14:41 volumio volumio[1010]: info: May 16 16:14:41 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:41 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:41 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-122.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D145047572fba4e4beef4c26df327ea1e9f12b793f897454cb23c459a640edc80%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835ddae%2F0%2F1464465%2Faefe763d.195913601.11.122021%2F320.mp3&ext=.mp3" took 2 milliseconds May 16 16:14:41 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:41 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:41 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:41 volumio volumio[1010]: info: May 16 16:14:41 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:41 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:41 volumio volumio[1010]: info: ------------------------------ 4ms May 16 16:14:41 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:14:41 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:14:41 volumio volumio[1010]: info: ------------------------------ 3ms May 16 16:14:41 volumio volumio[1010]: info: sendMpdCommand play took 1 milliseconds May 16 16:14:42 volumio volumio[1010]: info: May 16 16:14:42 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:42 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:42 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:42 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:42 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:42 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:42 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:42 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:42 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:42 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:42 volumio volumio[1010]: info: CorePlayQueue::getTrack 154 May 16 16:14:42 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":292,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"One More Night","artist":"Phil Collins","album":"Love Songs","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-122.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D145047572fba4e4beef4c26df327ea1e9f12b793f897454cb23c459a640edc80%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835ddae%2F0%2F1464465%2Faefe763d.195913601.11.122021%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:42 volumio volumio[1010]: verbose: CURRENT POSITION 154 May 16 16:14:42 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:42 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:14:42 volumio volumio[1010]: info: ------------------------------ 4ms May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:14:44 volumio volumio[1010]: info: ControllerMpd::next May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:14:44 volumio volumio[1010]: info: May 16 16:14:44 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:44 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:44 volumio volumio[1010]: info: May 16 16:14:44 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand next took 15 milliseconds May 16 16:14:44 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:44 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:44 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:44 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getTrack 154 May 16 16:14:44 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:14:44 volumio volumio[1010]: verbose: CURRENT POSITION 154 May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getTrack 155 May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getTrack 155 May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getTrack 155 May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:14:44 volumio volumio[1010]: info: ------------------------------ 13ms May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getTrack 155 May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand stop took 12 milliseconds May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:14:44 volumio volumio[1010]: info: May 16 16:14:44 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:44 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand clear took 12 milliseconds May 16 16:14:44 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:44 volumio volumio[1010]: info: ------------------------------ 15ms May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D473eee41e2c0863d82a079e3f08d9cfb5e579a1219b2ac7fde665236f4112b9b%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835a2bc%2F40443%2F8089797%2F2412c091.167098403.8.111240659%2F320.mp3&ext=.mp3" May 16 16:14:44 volumio volumio[1010]: info: May 16 16:14:44 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:44 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D473eee41e2c0863d82a079e3f08d9cfb5e579a1219b2ac7fde665236f4112b9b%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835a2bc%2F40443%2F8089797%2F2412c091.167098403.8.111240659%2F320.mp3&ext=.mp3" took 2 milliseconds May 16 16:14:44 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:44 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:44 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:44 volumio volumio[1010]: info: May 16 16:14:44 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:44 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:44 volumio volumio[1010]: info: ------------------------------ 3ms May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:14:44 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand play took 2 milliseconds May 16 16:14:44 volumio volumio[1010]: info: May 16 16:14:44 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:44 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:44 volumio volumio[1010]: info: May 16 16:14:44 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand status took 30 milliseconds May 16 16:14:44 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:44 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getTrack 155 May 16 16:14:44 volumio volumio[1010]: 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":"320 Kbps","isStreaming":false,"title":"芳踪何处","artist":"新时代轻音乐团","album":"懈逅柔情","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D473eee41e2c0863d82a079e3f08d9cfb5e579a1219b2ac7fde665236f4112b9b%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835a2bc%2F40443%2F8089797%2F2412c091.167098403.8.111240659%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:44 volumio volumio[1010]: verbose: CURRENT POSITION 155 May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:14:44 volumio volumio[1010]: info: ------------------------------ 34ms May 16 16:14:44 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 16:14:44 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:44 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getTrack 155 May 16 16:14:44 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":835,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"芳踪何处","artist":"新时代轻音乐团","album":"懈逅柔情","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D473eee41e2c0863d82a079e3f08d9cfb5e579a1219b2ac7fde665236f4112b9b%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835a2bc%2F40443%2F8089797%2F2412c091.167098403.8.111240659%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:44 volumio volumio[1010]: verbose: CURRENT POSITION 155 May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:44 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:44 volumio volumio[1010]: info: ------------------------------ 16ms May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:44 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:44 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:44 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:44 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:14:49 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 16:14:49 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 16:14:49 volumio volumio[1010]: info: Discovery: Getting this device information May 16 16:14:49 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:14:49 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 16:14:50 volumio volumio[1010]: verbose: New Socket.io Connection to 192.168.0.145:3000 from 192.168.0.201 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:14:51 volumio volumio[1010]: info: ControllerMpd::next May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:14:51 volumio volumio[1010]: info: May 16 16:14:51 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:51 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:51 volumio volumio[1010]: info: May 16 16:14:51 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand next took 12 milliseconds May 16 16:14:51 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:51 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:51 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:51 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 155 May 16 16:14:51 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:14:51 volumio volumio[1010]: verbose: CURRENT POSITION 155 May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 156 May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 156 May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 156 May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:14:51 volumio volumio[1010]: info: ------------------------------ 17ms May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 156 May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand stop took 9 milliseconds May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:51 volumio volumio[1010]: info: May 16 16:14:51 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:51 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand clear took 28 milliseconds May 16 16:14:51 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:51 volumio volumio[1010]: info: ------------------------------ 38ms May 16 16:14:51 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-109.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4be618e05e633bbfec7f42b792a2975da502512b63e772c3d559852e53d3c4cc%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835e0be%2F32927%2F24656%2Fbba63742.56240232.12.34027145%2F320.mp3&ext=.mp3" May 16 16:14:51 volumio volumio[1010]: info: May 16 16:14:51 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:51 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-109.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4be618e05e633bbfec7f42b792a2975da502512b63e772c3d559852e53d3c4cc%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835e0be%2F32927%2F24656%2Fbba63742.56240232.12.34027145%2F320.mp3&ext=.mp3" took 2 milliseconds May 16 16:14:51 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:51 volumio volumio[1010]: info: May 16 16:14:51 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:51 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:51 volumio volumio[1010]: info: ------------------------------ 3ms May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:14:51 volumio volumio[1010]: info: ------------------------------ 3ms May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand play took 1 milliseconds May 16 16:14:51 volumio volumio[1010]: info: May 16 16:14:51 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:51 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:51 volumio volumio[1010]: info: May 16 16:14:51 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand status took 25 milliseconds May 16 16:14:51 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand status took 2 milliseconds May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:51 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 156 May 16 16:14:51 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nothing Burns Like The Cold","artist":"Snoh Aalegra","album":"FEELS","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-109.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4be618e05e633bbfec7f42b792a2975da502512b63e772c3d559852e53d3c4cc%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835e0be%2F32927%2F24656%2Fbba63742.56240232.12.34027145%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:51 volumio volumio[1010]: verbose: CURRENT POSITION 156 May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:14:51 volumio volumio[1010]: info: ------------------------------ 30ms May 16 16:14:51 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:14:51 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:51 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 156 May 16 16:14:51 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":210,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nothing Burns Like The Cold","artist":"Snoh Aalegra","album":"FEELS","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-109.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4be618e05e633bbfec7f42b792a2975da502512b63e772c3d559852e53d3c4cc%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835e0be%2F32927%2F24656%2Fbba63742.56240232.12.34027145%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:51 volumio volumio[1010]: verbose: CURRENT POSITION 156 May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:51 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:51 volumio volumio[1010]: info: ------------------------------ 21ms May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:51 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:51 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:51 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:51 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:14:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 16:14:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 16:14:52 volumio volumio[1010]: info: Discovery: Getting this device information May 16 16:14:52 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:14:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:14:55 volumio volumio[1010]: info: ControllerMpd::next May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:14:55 volumio volumio[1010]: info: May 16 16:14:55 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:55 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:55 volumio volumio[1010]: info: May 16 16:14:55 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand next took 12 milliseconds May 16 16:14:55 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:55 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:55 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand status took 2 milliseconds May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:55 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 156 May 16 16:14:55 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:14:55 volumio volumio[1010]: verbose: CURRENT POSITION 156 May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 157 May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 157 May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 157 May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:14:55 volumio volumio[1010]: info: ------------------------------ 17ms May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 157 May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand stop took 8 milliseconds May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:55 volumio volumio[1010]: info: May 16 16:14:55 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:55 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand clear took 26 milliseconds May 16 16:14:55 volumio volumio[1010]: error: updateQueue error: null May 16 16:14:55 volumio volumio[1010]: info: ------------------------------ 46ms May 16 16:14:55 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-24.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1d12122d315b8086a9418761e7eb9c150e15428bc1b6a00628ba8ef74332cddb%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835cfd3%2F45907%2F14558%2F0ee726eb.30655484.11.20814004%2F320.mp3&ext=.mp3" May 16 16:14:55 volumio volumio[1010]: info: May 16 16:14:55 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:55 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-24.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1d12122d315b8086a9418761e7eb9c150e15428bc1b6a00628ba8ef74332cddb%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835cfd3%2F45907%2F14558%2F0ee726eb.30655484.11.20814004%2F320.mp3&ext=.mp3" took 2 milliseconds May 16 16:14:55 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:55 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:14:55 volumio volumio[1010]: info: May 16 16:14:55 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:14:55 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:14:55 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:14:55 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand play took 1 milliseconds May 16 16:14:55 volumio volumio[1010]: info: May 16 16:14:55 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:55 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:55 volumio volumio[1010]: info: May 16 16:14:55 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand status took 22 milliseconds May 16 16:14:55 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:55 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 157 May 16 16:14:55 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nah Neh Nah","artist":"Vaya Con Dios","album":"Thank You All !","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-24.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1d12122d315b8086a9418761e7eb9c150e15428bc1b6a00628ba8ef74332cddb%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835cfd3%2F45907%2F14558%2F0ee726eb.30655484.11.20814004%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:55 volumio volumio[1010]: verbose: CURRENT POSITION 157 May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:14:55 volumio volumio[1010]: info: ------------------------------ 27ms May 16 16:14:55 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 16:14:55 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:14:55 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:14:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 157 May 16 16:14:55 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":295,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Nah Neh Nah","artist":"Vaya Con Dios","album":"Thank You All !","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-24.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1d12122d315b8086a9418761e7eb9c150e15428bc1b6a00628ba8ef74332cddb%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6835cfd3%2F45907%2F14558%2F0ee726eb.30655484.11.20814004%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:14:55 volumio volumio[1010]: verbose: CURRENT POSITION 157 May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:14:55 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:55 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:14:55 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:14:55 volumio volumio[1010]: info: ------------------------------ 23ms May 16 16:14:56 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:56 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:56 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:56 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:14:56 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:14:56 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:14:56 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:15:01 volumio volumio[1010]: info: ControllerMpd::next May 16 16:15:01 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:15:01 volumio volumio[1010]: info: May 16 16:15:01 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:01 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:01 volumio volumio[1010]: info: May 16 16:15:01 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:01 volumio volumio[1010]: info: sendMpdCommand next took 6 milliseconds May 16 16:15:01 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:01 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:01 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:01 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:01 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:15:01 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:01 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:01 volumio volumio[1010]: info: CorePlayQueue::getTrack 157 May 16 16:15:01 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:15:01 volumio volumio[1010]: verbose: CURRENT POSITION 157 May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:01 volumio volumio[1010]: info: CorePlayQueue::getTrack 158 May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:01 volumio volumio[1010]: info: CorePlayQueue::getTrack 158 May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:15:01 volumio volumio[1010]: info: CorePlayQueue::getTrack 158 May 16 16:15:01 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:15:01 volumio volumio[1010]: info: ------------------------------ 16ms May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:01 volumio volumio[1010]: info: CorePlayQueue::getTrack 158 May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:01 volumio volumio[1010]: info: sendMpdCommand stop took 14 milliseconds May 16 16:15:01 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:15:01 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:01 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:01 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:01 volumio volumio[1010]: info: May 16 16:15:01 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:01 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:01 volumio volumio[1010]: info: sendMpdCommand clear took 46 milliseconds May 16 16:15:01 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:01 volumio volumio[1010]: info: ------------------------------ 12ms May 16 16:15:01 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:15:05 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay May 16 16:15:05 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:15:05 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:05 volumio volumio[1010]: info: CorePlayQueue::getTrack 158 May 16 16:15:05 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:15:05 volumio volumio[1010]: info: CorePlayQueue::getTrack 158 May 16 16:15:05 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:15:05 volumio volumio[1010]: info: sendMpdCommand stop took 0 milliseconds May 16 16:15:05 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:15:05 volumio volumio[1010]: info: May 16 16:15:05 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:05 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:05 volumio volumio[1010]: info: sendMpdCommand clear took 1 milliseconds May 16 16:15:05 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:05 volumio volumio[1010]: info: ------------------------------ 8ms May 16 16:15:06 volumio volumio[1010]: verbose: New Socket.io Connection to 192.168.0.145:3000 from 192.168.0.201 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 16 16:15:08 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:15:08 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:15:08 volumio volumio[1010]: info: CoreStateMachine::stop May 16 16:15:08 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:08 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:15:08 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:08 volumio volumio[1010]: info: CorePlayQueue::getTrack 159 May 16 16:15:08 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:15:08 volumio volumio[1010]: info: CorePlayQueue::getTrack 159 May 16 16:15:08 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:15:08 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock May 16 16:15:08 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock May 16 16:15:08 volumio volumio[1010]: info: sendMpdCommand stop took 1 milliseconds May 16 16:15:08 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:15:08 volumio volumio[1010]: info: May 16 16:15:08 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:08 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:08 volumio volumio[1010]: info: sendMpdCommand clear took 1 milliseconds May 16 16:15:08 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:08 volumio volumio[1010]: info: ------------------------------ 5ms May 16 16:15:18 volumio volumio[1010]: info: Preload queue cleared May 16 16:15:18 volumio volumio[1010]: info: CoreCommandRouter::volumioReplaceandPlayItems May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::ClearQueue May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::stop May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::clearPlayQueue May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::saveQueue May 16 16:15:18 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::addQueueItems May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::addQueueItems May 16 16:15:18 volumio volumio[1010]: info: Preload queue cleared May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2773108:298151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2773108:298151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/70503:5639257@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/70503:5639257@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/649371:71042@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/649371:71042@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/5304422:583795@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/5304422:583795@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/58661988:8927346@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/58661988:8927346@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/171256:17212@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/171256:17212@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/333480:19028505@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/333480:19028505@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/16201643:1767585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/16201643:1767585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17370500:1987056@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17370500:1987056@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/21870355:18882528@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/21870355:18882528@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/14142059:1539266@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/14142059:1539266@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/48769253:508394@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/48769253:508394@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/25687010:3036125@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/25687010:3036125@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/628412:22533165@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/628412:22533165@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/3545623:381150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/3545623:381150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/97519:2788795@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/97519:2788795@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/27756833:3318987@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/27756833:3318987@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2816574:298151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2816574:298151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/68103354:11257222@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/68103354:11257222@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/473458:288156@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/473458:288156@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/32789:21172317@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/32789:21172317@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/707386:3655005@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/707386:3655005@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/39366397@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/39366397@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17315:3963@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17315:3963@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/22847237:110673@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/22847237:110673@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/4087:4163442@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/4087:4163442@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/21459381:2429695@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/21459381:2429695@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/14095286:25079@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/14095286:25079@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/32521081:2522583@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/32521081:2522583@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/96294:31230@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/96294:31230@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/28932675:3550565@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/28932675:3550565@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/43805651:5844202@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/43805651:5844202@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/6283739:686180@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/6283739:686180@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/6703045:720280@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/6703045:720280@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/653698:3651229@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/653698:3651229@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17033136:1872149@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17033136:1872149@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/31554274:4163442@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/31554274:4163442@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/20731755:12753751@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/20731755:12753751@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/14672445:1606397@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/14672445:1606397@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/57075690:8527511@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/57075690:8527511@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/16201639:1767585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/16201639:1767585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/6818959:729852@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/6818959:729852@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/628803:1813962@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/628803:1813962@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/697121:76621@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/697121:76621@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/6807331:3184295@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/6807331:3184295@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/39368074:5081079@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/39368074:5081079@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/672716:953851@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/672716:953851@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/4264626:483895@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/4264626:483895@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/16083009:11766883@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/16083009:11766883@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/27511245:3293649@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/27511245:3293649@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/15176:691114@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/15176:691114@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/953490:109704@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/953490:109704@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/669508:20266930@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/669508:20266930@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/4503293:509942@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/4503293:509942@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::saveQueue May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock May 16 16:15:18 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::play index 53 May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::addQueueItems May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::addQueueItems May 16 16:15:18 volumio volumio[1010]: info: Preload queue cleared May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/665984:82909@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/665984:82909@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/27373919:3277248@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/27373919:3277248@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/752154:4259809@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/752154:4259809@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/4585831:4646959@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/4585831:4646959@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/34457090:4245596@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/34457090:4245596@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/44984335:6035612@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/44984335:6035612@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/75207964:13213028@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/75207964:13213028@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/632292:20321360@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/632292:20321360@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/23139939:2658571@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/23139939:2658571@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/436967:45474@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/436967:45474@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/15771620:20021398@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/15771620:20021398@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/125949:22016@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/125949:22016@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/32192243:36327113@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/32192243:36327113@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2385566:238555@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2385566:238555@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/47107424:2658571@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/47107424:2658571@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/44934809:7351266@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/44934809:7351266@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/10346271:1118945@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/10346271:1118945@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/44765262:7445439@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/44765262:7445439@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/54952:15177112@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/54952:15177112@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/1710802:2486907@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/1710802:2486907@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/23867456@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/23867456@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/20354619:2311009@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/20354619:2311009@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/7936274:6209356@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/7936274:6209356@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/20254649:2296696@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/20254649:2296696@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/30645378:3707656@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/30645378:3707656@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/10294530:1672742@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/10294530:1672742@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/67742261@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/67742261@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/646121:7132293@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/646121:7132293@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/33758084:4130666@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/33758084:4130666@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/634907:83133@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/634907:83133@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/27402780:3281633@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/27402780:3281633@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/609663:2077427@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/609663:2077427@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/21757019:7041520@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/21757019:7041520@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/799299:6735738@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/799299:6735738@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/21943:3374@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/21943:3374@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/46174148@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/46174148@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17585019:1942573@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17585019:1942573@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17412381:4016426@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17412381:4016426@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/654076:4625137@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/654076:4625137@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/40224744:5275253@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/40224744:5275253@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/46472833:6707461@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/46472833:6707461@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2678:10761@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2678:10761@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/31174074:3776938@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/31174074:3776938@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/38055573:18329735@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/38055573:18329735@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/147964:3385189@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/147964:3385189@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/45912893@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/45912893@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/35501534:4430762@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/35501534:4430762@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/33893595:10624995@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/33893595:10624995@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/149410:14505@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/149410:14505@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/4526:6195075@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/4526:6195075@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/28305253:3395988@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/28305253:3395988@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/36945744:4862836@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/36945744:4862836@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/36998702:4691542@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/36998702:4691542@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/21824:6349960@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/21824:6349960@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/986069:113923@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/986069:113923@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/57233:5576245@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/57233:5576245@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/664801:4664617@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/664801:4664617@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/492549:52314@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/492549:52314@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/28662138:9650150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/28662138:9650150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/34871347:4314522@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/34871347:4314522@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/632329:16653150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/632329:16653150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/62515:12363220@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/62515:12363220@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/19195308:2154205@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/19195308:2154205@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17328:2347643@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17328:2347643@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/30840407:10485273@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/30840407:10485273@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/630385:10640036@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/630385:10640036@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/14701552:1610687@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/14701552:1610687@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/59673:6068978@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/59673:6068978@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/28115:7956793@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/28115:7956793@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/18855:59523@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/18855:59523@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/19232475:2159233@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/19232475:2159233@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/688478:12807823@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/688478:12807823@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/47763:13200738@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/47763:13200738@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/207153:21019@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/207153:21019@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/653087:70463@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/653087:70463@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/799301:6735738@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/799301:6735738@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2294790:912173@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2294790:912173@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/664804:4861724@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/664804:4861724@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2678:169073@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2678:169073@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/629157:16653150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/629157:16653150@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/59063:4879@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/59063:4879@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17303:12502976@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17303:12502976@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/645943:2766710@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/645943:2766710@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/18067627:2001480@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/18067627:2001480@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/795252:89098@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/795252:89098@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/424931:136364@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/424931:136364@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/55682:4573@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/55682:4573@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/14650707:2498025@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/14650707:2498025@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/370843:1590476@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/370843:1590476@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/17309:2501088@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/17309:2501088@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/528530:5788885@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/528530:5788885@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/37361268:4730873@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/37361268:4730873@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/96296:17449145@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/96296:17449145@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/329918:1998006@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/329918:1998006@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/216056@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/216056@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/44123896@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/44123896@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/781328:9172697@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/781328:9172697@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/629695:4908157@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/629695:4908157@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/76115:5245664@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/76115:5245664@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/31499205:3818652@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/31499205:3818652@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/122021:2001480@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/122021:2001480@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/632322:24831232@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/632322:24831232@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/34027145:3936731@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/34027145:3936731@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/670475:35100585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/670475:35100585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/9066774@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/9066774@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/47115651@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/47115651@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/7065499:750729@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/7065499:750729@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/134632:9945490@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/134632:9945490@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/206022:20927@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/206022:20927@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/1787083:177285@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/1787083:177285@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/628660:606543@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/628660:606543@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/628475:22497592@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/628475:22497592@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/37848211:4808573@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/37848211:4808573@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/18580162:11501920@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/18580162:11501920@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/25727188:3579166@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/25727188:3579166@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/170164:9239516@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/170164:9239516@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/28435768:3401812@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/28435768:3401812@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/322740:32349@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/322740:32349@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/766867:169138@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/766867:169138@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/25873851:3064389@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/25873851:3064389@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2773120:298151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2773120:298151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/42935725:5694870@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/42935725:5694870@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/10776533:5899151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/10776533:5899151@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/632213:4702221@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/632213:4702221@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/36544771:4602332@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/36544771:4602332@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/12226081:9352840@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/12226081:9352840@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/610411:7728567@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/610411:7728567@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/36544774:4602332@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/36544774:4602332@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/36787647:22333923@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/36787647:22333923@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2214611:4334256@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2214611:4334256@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/45412517:6093531@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/45412517:6093531@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/215101:3385189@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/215101:3385189@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/12226149@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/12226149@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/333432:3389009@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/333432:3389009@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/807211:90136@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/807211:90136@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/792543:5639257@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/792543:5639257@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/450647:3029966@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/450647:3029966@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/677831:1033148@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/677831:1033148@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/38537:3193@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/38537:3193@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/25265809:2975445@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/25265809:2975445@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/20513367:2490685@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/20513367:2490685@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2164315:5639257@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2164315:5639257@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/787645:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/787645:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/797888:5600808@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/797888:5600808@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/392167:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/392167:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/4301:12343076@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/4301:12343076@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/450110:46628@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/450110:46628@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/215651:3385176@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/215651:3385176@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/2181364:215667@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/2181364:215667@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/804499:2509274@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/804499:2509274@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/637810:68389@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/637810:68389@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/21852780:2510846@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/21852780:2510846@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/790100:88043@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/790100:88043@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/6620906:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/6620906:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/71099:13527036@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/71099:13527036@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/8573:5514632@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/8573:5514632@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/64814:2497648@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/64814:2497648@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/796057:88801@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/796057:88801@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/792546:7453531@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/792546:7453531@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/56805:5664585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/56805:5664585@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/27070018:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/27070018:9593444@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/47763:10624884@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/47763:10624884@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/454394:47078@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/454394:47078@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/37848249:4808576@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/37848249:4808576@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/246660:49408@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/246660:49408@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/4722977:527714@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/4722977:527714@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/43183:90368@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/43183:90368@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/68556:19533934@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/68556:19533934@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/357950:13367872@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/357950:13367872@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/12172:10217807@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/12172:10217807@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/827172:305374@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/827172:305374@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/667984:498478@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/667984:498478@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Adding Item to queue: yandex_music/track/31105795:3767600@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: Using cached record of: yandex_music/track/31105795:3767600@544019038:1025 May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::stop May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:18 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::saveQueue May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::getTrack 53 May 16 16:15:18 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:15:18 volumio volumio[1010]: info: CorePlayQueue::getTrack 53 May 16 16:15:18 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:15:18 volumio volumio[1010]: info: sendMpdCommand stop took 45 milliseconds May 16 16:15:18 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:15:18 volumio volumio[1010]: info: May 16 16:15:18 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:18 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:18 volumio volumio[1010]: info: sendMpdCommand clear took 1 milliseconds May 16 16:15:18 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:18 volumio volumio[1010]: info: ------------------------------ 9ms May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-106.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da09d7a40cf9547e2f3d6e91681ffba2f909aee93da10c657778c4a1a5039f7ea%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683532ae%2F0%2F56650%2F31d265a5.6017833.4503293%2F320.mp3&ext=.mp3" May 16 16:15:19 volumio volumio[1010]: info: May 16 16:15:19 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:19 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:19 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-106.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da09d7a40cf9547e2f3d6e91681ffba2f909aee93da10c657778c4a1a5039f7ea%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683532ae%2F0%2F56650%2F31d265a5.6017833.4503293%2F320.mp3&ext=.mp3" took 1 milliseconds May 16 16:15:19 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:19 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:19 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:19 volumio volumio[1010]: info: May 16 16:15:19 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:19 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:19 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:15:19 volumio volumio[1010]: info: ------------------------------ 1ms May 16 16:15:19 volumio volumio[1010]: info: sendMpdCommand play took 2 milliseconds May 16 16:15:19 volumio volumio[1010]: info: May 16 16:15:19 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:19 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:19 volumio volumio[1010]: info: May 16 16:15:19 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:19 volumio volumio[1010]: info: sendMpdCommand status took 24 milliseconds May 16 16:15:19 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:19 volumio volumio[1010]: info: sendMpdCommand status took 2 milliseconds May 16 16:15:19 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:19 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:19 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:19 volumio volumio[1010]: info: CorePlayQueue::getTrack 53 May 16 16:15:19 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Opium","artist":"Dead Can Dance","album":"Anastasis","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-106.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da09d7a40cf9547e2f3d6e91681ffba2f909aee93da10c657778c4a1a5039f7ea%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683532ae%2F0%2F56650%2F31d265a5.6017833.4503293%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:19 volumio volumio[1010]: verbose: CURRENT POSITION 53 May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:15:19 volumio volumio[1010]: info: ------------------------------ 29ms May 16 16:15:19 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 3 milliseconds May 16 16:15:19 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:19 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:19 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:19 volumio volumio[1010]: info: CorePlayQueue::getTrack 53 May 16 16:15:19 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":345,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Opium","artist":"Dead Can Dance","album":"Anastasis","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-106.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da09d7a40cf9547e2f3d6e91681ffba2f909aee93da10c657778c4a1a5039f7ea%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D683532ae%2F0%2F56650%2F31d265a5.6017833.4503293%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:19 volumio volumio[1010]: verbose: CURRENT POSITION 53 May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:19 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:19 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:19 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:19 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:19 volumio volumio[1010]: info: ------------------------------ 24ms May 16 16:15:19 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:19 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:19 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:19 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:19 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:19 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:15:21 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 16:15:21 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 16:15:21 volumio volumio[1010]: info: Discovery: Getting this device information May 16 16:15:21 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:15:21 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 16:15:22 volumio volumio[1010]: verbose: New Socket.io Connection to 192.168.0.145:3000 from 192.168.0.201 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 16 16:15:25 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:15:28 volumio volumio[1010]: info: ControllerMpd::next May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:15:28 volumio volumio[1010]: info: May 16 16:15:28 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:28 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:28 volumio volumio[1010]: info: May 16 16:15:28 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand next took 16 milliseconds May 16 16:15:28 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:28 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:28 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:28 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 53 May 16 16:15:28 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:15:28 volumio volumio[1010]: verbose: CURRENT POSITION 53 May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 54 May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 54 May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 54 May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:15:28 volumio volumio[1010]: info: ------------------------------ 16ms May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 54 May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand stop took 8 milliseconds May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:28 volumio volumio[1010]: info: May 16 16:15:28 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:28 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand clear took 19 milliseconds May 16 16:15:28 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:28 volumio volumio[1010]: info: ------------------------------ 37ms May 16 16:15:28 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-80.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1cb6f508aae9a53c0f87f99bf2c8abc6e854c0199d777ba8a5072b808ebc4250%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68351daa%2F0%2F102394%2Fu%2F23072eed-aaad-4458-b485-8fba0dd21a30.1%2F320.mp3&ext=.mp3" May 16 16:15:28 volumio volumio[1010]: info: May 16 16:15:28 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:28 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-80.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1cb6f508aae9a53c0f87f99bf2c8abc6e854c0199d777ba8a5072b808ebc4250%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68351daa%2F0%2F102394%2Fu%2F23072eed-aaad-4458-b485-8fba0dd21a30.1%2F320.mp3&ext=.mp3" took 1 milliseconds May 16 16:15:28 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:28 volumio volumio[1010]: info: May 16 16:15:28 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:28 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:28 volumio volumio[1010]: info: ------------------------------ 3ms May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:15:28 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand play took 1 milliseconds May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 16:15:28 volumio volumio[1010]: info: Discovery: Getting this device information May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 16:15:28 volumio volumio[1010]: info: May 16 16:15:28 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:28 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand status took 23 milliseconds May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:28 volumio volumio[1010]: info: May 16 16:15:28 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:28 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:28 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 54 May 16 16:15:28 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Purple Rain","artist":"Etta James","album":"All The Way","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-80.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1cb6f508aae9a53c0f87f99bf2c8abc6e854c0199d777ba8a5072b808ebc4250%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68351daa%2F0%2F102394%2Fu%2F23072eed-aaad-4458-b485-8fba0dd21a30.1%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:28 volumio volumio[1010]: verbose: CURRENT POSITION 54 May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:15:28 volumio volumio[1010]: info: ------------------------------ 28ms May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand status took 2 milliseconds May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:28 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:15:28 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:28 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getTrack 54 May 16 16:15:28 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Purple Rain","artist":"Etta James","album":"All The Way","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-80.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D1cb6f508aae9a53c0f87f99bf2c8abc6e854c0199d777ba8a5072b808ebc4250%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68351daa%2F0%2F102394%2Fu%2F23072eed-aaad-4458-b485-8fba0dd21a30.1%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:28 volumio volumio[1010]: verbose: CURRENT POSITION 54 May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:28 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:28 volumio volumio[1010]: info: ------------------------------ 18ms May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:28 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:28 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:28 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:29 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:15:32 volumio volumio[1010]: info: ControllerMpd::next May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:15:32 volumio volumio[1010]: info: May 16 16:15:32 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:32 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:32 volumio volumio[1010]: info: May 16 16:15:32 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand next took 20 milliseconds May 16 16:15:32 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:32 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:32 volumio volumio[1010]: info: ------------------------------ 1ms May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:32 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 54 May 16 16:15:32 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:15:32 volumio volumio[1010]: verbose: CURRENT POSITION 54 May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 55 May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 55 May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 55 May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:15:32 volumio volumio[1010]: info: ------------------------------ 14ms May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 55 May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand stop took 9 milliseconds May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:32 volumio volumio[1010]: info: May 16 16:15:32 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:32 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand clear took 25 milliseconds May 16 16:15:32 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:32 volumio volumio[1010]: info: ------------------------------ 42ms May 16 16:15:32 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-113.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250d1f90dd2e445449c9832c2f2355413d79d2ff285b29561b9a91cbb548ff35%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68356c2f%2F19991%2F10183%2Ff7396653.63130101.11.27373919%2F320.mp3&ext=.mp3" May 16 16:15:32 volumio volumio[1010]: info: May 16 16:15:32 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:32 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-113.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250d1f90dd2e445449c9832c2f2355413d79d2ff285b29561b9a91cbb548ff35%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68356c2f%2F19991%2F10183%2Ff7396653.63130101.11.27373919%2F320.mp3&ext=.mp3" took 1 milliseconds May 16 16:15:32 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:32 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:32 volumio volumio[1010]: info: May 16 16:15:32 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:32 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:32 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:15:32 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand play took 1 milliseconds May 16 16:15:32 volumio volumio[1010]: info: May 16 16:15:32 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:32 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:32 volumio volumio[1010]: info: May 16 16:15:32 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand status took 23 milliseconds May 16 16:15:32 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 0 milliseconds May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:32 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 55 May 16 16:15:32 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":126,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Yesterday","artist":"The Beatles","album":"Help!","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-113.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250d1f90dd2e445449c9832c2f2355413d79d2ff285b29561b9a91cbb548ff35%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68356c2f%2F19991%2F10183%2Ff7396653.63130101.11.27373919%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:32 volumio volumio[1010]: verbose: CURRENT POSITION 55 May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:15:32 volumio volumio[1010]: info: ------------------------------ 27ms May 16 16:15:32 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:15:32 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:32 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 55 May 16 16:15:32 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":126,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Yesterday","artist":"The Beatles","album":"Help!","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-113.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D250d1f90dd2e445449c9832c2f2355413d79d2ff285b29561b9a91cbb548ff35%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68356c2f%2F19991%2F10183%2Ff7396653.63130101.11.27373919%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:32 volumio volumio[1010]: verbose: CURRENT POSITION 55 May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:32 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:32 volumio volumio[1010]: info: ------------------------------ 21ms May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:32 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:32 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:32 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:32 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::volumioNext May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::next May 16 16:15:34 volumio volumio[1010]: info: ControllerMpd::next May 16 16:15:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand next May 16 16:15:34 volumio volumio[1010]: info: May 16 16:15:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:34 volumio volumio[1010]: info: May 16 16:15:34 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:34 volumio volumio[1010]: info: sendMpdCommand next took 7 milliseconds May 16 16:15:34 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:34 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:34 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:34 volumio volumio[1010]: info: sendMpdCommand status took 2 milliseconds May 16 16:15:34 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:34 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 55 May 16 16:15:34 volumio volumio[1010]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 16:15:34 volumio volumio[1010]: verbose: CURRENT POSITION 55 May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::play index undefined May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 56 May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 56 May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 16 16:15:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 56 May 16 16:15:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:15:34 volumio volumio[1010]: info: ------------------------------ 15ms May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 56 May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:34 volumio volumio[1010]: info: sendMpdCommand stop took 8 milliseconds May 16 16:15:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 16 16:15:34 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:34 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:34 volumio volumio[1010]: info: May 16 16:15:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:34 volumio volumio[1010]: info: sendMpdCommand clear took 33 milliseconds May 16 16:15:34 volumio volumio[1010]: error: updateQueue error: null May 16 16:15:34 volumio volumio[1010]: info: ------------------------------ 37ms May 16 16:15:34 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. May 16 16:15:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D89e670179a20ae0a313ab69e390e7e553115142b53355def0dd8f8308014c823%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6834cb39%2F0%2F235014%2F0a0e6468.51711048.7.34550511%2F320.mp3&ext=.mp3" May 16 16:15:34 volumio volumio[1010]: info: May 16 16:15:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:34 volumio volumio[1010]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D89e670179a20ae0a313ab69e390e7e553115142b53355def0dd8f8308014c823%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6834cb39%2F0%2F235014%2F0a0e6468.51711048.7.34550511%2F320.mp3&ext=.mp3" took 1 milliseconds May 16 16:15:34 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:34 volumio volumio[1010]: verbose: MPD COMMAND [object Object] May 16 16:15:34 volumio volumio[1010]: info: May 16 16:15:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update May 16 16:15:34 volumio volumio[1010]: info: Ignoring MPD Status Update May 16 16:15:34 volumio volumio[1010]: info: ------------------------------ 2ms May 16 16:15:34 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 16 16:15:34 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 16 16:15:34 volumio volumio[1010]: info: ------------------------------ 1ms May 16 16:15:34 volumio volumio[1010]: info: sendMpdCommand play took 2 milliseconds May 16 16:15:35 volumio volumio[1010]: info: May 16 16:15:35 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:35 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:35 volumio volumio[1010]: info: May 16 16:15:35 volumio volumio[1010]: ---------------------------- MPD announces state update: player May 16 16:15:35 volumio volumio[1010]: info: ControllerMpd::getState May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 16 16:15:35 volumio volumio[1010]: info: sendMpdCommand status took 2 milliseconds May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:35 volumio volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::parseState May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:15:35 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 16:15:35 volumio volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:35 volumio volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 16 16:15:35 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:35 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:35 volumio volumio[1010]: info: CorePlayQueue::getTrack 56 May 16 16:15:35 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365,"duration":142,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Shoop Shoop Song","artist":"Aretha Franklin","album":"The 25 Best Songs","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D89e670179a20ae0a313ab69e390e7e553115142b53355def0dd8f8308014c823%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6834cb39%2F0%2F235014%2F0a0e6468.51711048.7.34550511%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:35 volumio volumio[1010]: verbose: CURRENT POSITION 56 May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 16 16:15:35 volumio volumio[1010]: info: ControllerMpd::pushState May 16 16:15:35 volumio volumio[1010]: info: CoreCommandRouter::servicePushState May 16 16:15:35 volumio volumio[1010]: info: CorePlayQueue::getTrack 56 May 16 16:15:35 volumio volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":783,"duration":142,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Shoop Shoop Song","artist":"Aretha Franklin","album":"The 25 Best Songs","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-134.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D89e670179a20ae0a313ab69e390e7e553115142b53355def0dd8f8308014c823%2Clid%3D223%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6834cb39%2F0%2F235014%2F0a0e6468.51711048.7.34550511%2F320.mp3&ext=.mp3","trackType":"mp3"} May 16 16:15:35 volumio volumio[1010]: verbose: CURRENT POSITION 56 May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 16 16:15:35 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:35 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:15:35 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::pushState May 16 16:15:35 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState May 16 16:15:35 volumio volumio[1010]: info: ------------------------------ 11ms May 16 16:15:35 volumio volumio[1010]: info: ------------------------------ 17ms May 16 16:15:35 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:35 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:35 volumio volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 16 16:15:35 volumio volumio[1010]: info: CoreStateMachine::getQueue May 16 16:15:35 volumio volumio[1010]: info: CorePlayQueue::getQueue May 16 16:15:35 volumio volumio[1010]: info: touch_display: Setting screensaver timeout to 0 seconds. May 16 16:15:37 volumio volumio[1010]: verbose: New Socket.io Connection to 192.168.0.145:3000 from 192.168.0.201 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 16 16:15:46 volumio volumio[1010]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 16:15:46 volumio volumio[1010]: Error: read ECONNRESET May 16 16:15:46 volumio volumio[1010]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { May 16 16:15:46 volumio volumio[1010]: errno: -104, May 16 16:15:46 volumio volumio[1010]: code: 'ECONNRESET', May 16 16:15:46 volumio volumio[1010]: syscall: 'read' May 16 16:15:46 volumio volumio[1010]: } May 16 16:15:46 volumio volumio[1010]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 16:15:47 volumio sudo[18924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 16:14 May 16 16:15:47 volumio sudo[18924]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"