-- Logs begin at Mon 2024-10-07 05:49:14 UTC, end at Sat 2024-10-26 13:23:43 UTC. -- Oct 26 13:22:01 volumio volumio[1093]: info: CoreCommandRouter::volumioPrevious Oct 26 13:22:01 volumio volumio[1093]: info: CoreStateMachine::previous Oct 26 13:22:01 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:01 volumio volumio[1093]: info: ControllerMpd::seek Oct 26 13:22:01 volumio volumio[1093]: error: null Oct 26 13:22:01 volumio volumio[1093]: info: Oct 26 13:22:01 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:01 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:01 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:01 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Oct 26 13:22:01 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:01 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:01 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 13:22:01 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:01 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:01 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:01 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:01 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"39-31.5Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/39-31.5Hz.dsf","trackType":"dsf"} Oct 26 13:22:01 volumio volumio[1093]: verbose: CURRENT POSITION 38 Oct 26 13:22:01 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:01 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Oct 26 13:22:01 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Oct 26 13:22:01 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:01 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:01 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:01 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:01 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:01 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:01 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:01 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:01 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:01 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:01 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:01 volumio volumio[1093]: info: ------------------------------ 24ms Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::volumioPrevious Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::previous Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::stop Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::serviceStop Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::serviceStop Oct 26 13:22:04 volumio volumio[1093]: info: ControllerMpd::stop Oct 26 13:22:04 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 13:22:04 volumio volumio[1093]: info: Oct 26 13:22:04 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:04 volumio volumio[1093]: info: sendMpdCommand stop took 15 milliseconds Oct 26 13:22:04 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:04 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:04 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Oct 26 13:22:04 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:04 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:04 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 13:22:04 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:04 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:04 volumio volumio[1093]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"39-31.5Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/39-31.5Hz.dsf","trackType":"dsf"} Oct 26 13:22:04 volumio volumio[1093]: verbose: CURRENT POSITION 38 Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::syncState stateService stop Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:04 volumio volumio[1093]: info: No code Oct 26 13:22:04 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:04 volumio volumio[1093]: info: CorePlayQueue::getTrack 38 Oct 26 13:22:04 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:04 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:04 volumio volumio[1093]: info: ------------------------------ 20ms Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::play index undefined Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand stop took 1 milliseconds Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 13:22:05 volumio volumio[1093]: info: Oct 26 13:22:05 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:05 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand clear took 1 milliseconds Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf" Oct 26 13:22:05 volumio volumio[1093]: error: updateQueue error: null Oct 26 13:22:05 volumio volumio[1093]: info: Oct 26 13:22:05 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:05 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:05 volumio volumio[1093]: info: ------------------------------ 3ms Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf" took 2 milliseconds Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Oct 26 13:22:05 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand play took 1 milliseconds Oct 26 13:22:05 volumio volumio[1093]: info: Oct 26 13:22:05 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:05 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:05 volumio volumio[1093]: info: Oct 26 13:22:05 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand status took 5 milliseconds Oct 26 13:22:05 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:05 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"38-25Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf","trackType":"dsf"} Oct 26 13:22:05 volumio volumio[1093]: verbose: CURRENT POSITION 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Oct 26 13:22:05 volumio volumio[1093]: info: ------------------------------ 12ms Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:05 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"38-25Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf","trackType":"dsf"} Oct 26 13:22:05 volumio volumio[1093]: verbose: CURRENT POSITION 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Oct 26 13:22:05 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:05 volumio volumio[1093]: info: ------------------------------ 27ms Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::volumioPrevious Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::previous Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: info: ControllerMpd::seek Oct 26 13:22:05 volumio volumio[1093]: error: null Oct 26 13:22:05 volumio volumio[1093]: info: Oct 26 13:22:05 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:05 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:05 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 26 13:22:05 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:05 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"38-25Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf","trackType":"dsf"} Oct 26 13:22:05 volumio volumio[1093]: verbose: CURRENT POSITION 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Oct 26 13:22:05 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:05 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:05 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:05 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:05 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:05 volumio volumio[1093]: info: ------------------------------ 19ms Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPrevious Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::previous Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::stop Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::serviceStop Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::serviceStop Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::stop Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand stop took 12 milliseconds Oct 26 13:22:07 volumio volumio[1093]: info: Oct 26 13:22:07 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:07 volumio volumio[1093]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"38-25Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf","trackType":"dsf"} Oct 26 13:22:07 volumio volumio[1093]: verbose: CURRENT POSITION 37 Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState stateService stop Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: No code Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: ------------------------------ 19ms Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::play index undefined Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand stop took 0 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 13:22:07 volumio volumio[1093]: info: Oct 26 13:22:07 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:07 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand clear took 1 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf" Oct 26 13:22:07 volumio volumio[1093]: error: updateQueue error: null Oct 26 13:22:07 volumio volumio[1093]: info: Oct 26 13:22:07 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:07 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:07 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf" took 2 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Oct 26 13:22:07 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand play took 1 milliseconds Oct 26 13:22:07 volumio volumio[1093]: info: Oct 26 13:22:07 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:07 volumio volumio[1093]: info: Oct 26 13:22:07 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand status took 6 milliseconds Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand status took 3 milliseconds Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":11,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37-20Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf","trackType":"dsf"} Oct 26 13:22:07 volumio volumio[1093]: verbose: CURRENT POSITION 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Oct 26 13:22:07 volumio volumio[1093]: info: ------------------------------ 13ms Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":11,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37-20Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf","trackType":"dsf"} Oct 26 13:22:07 volumio volumio[1093]: verbose: CURRENT POSITION 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Oct 26 13:22:07 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: ------------------------------ 38ms Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPrevious Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::previous Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::stop Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::serviceStop Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::serviceStop Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::stop Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 13:22:07 volumio volumio[1093]: info: Oct 26 13:22:07 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand stop took 19 milliseconds Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:07 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 13:22:07 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:07 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37-20Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf","trackType":"dsf"} Oct 26 13:22:07 volumio volumio[1093]: verbose: CURRENT POSITION 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState stateService stop Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: No code Oct 26 13:22:07 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:07 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:07 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:07 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:07 volumio volumio[1093]: info: ------------------------------ 25ms Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::play index undefined Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 13:22:08 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Oct 26 13:22:08 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/36-10Hz.dsf Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand stop took 1 milliseconds Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 13:22:08 volumio volumio[1093]: info: Oct 26 13:22:08 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:08 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand clear took 1 milliseconds Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/36-10Hz.dsf" Oct 26 13:22:08 volumio volumio[1093]: error: updateQueue error: null Oct 26 13:22:08 volumio volumio[1093]: info: Oct 26 13:22:08 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:08 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:08 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/36-10Hz.dsf" took 2 milliseconds Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Oct 26 13:22:08 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand play took 2 milliseconds Oct 26 13:22:08 volumio volumio[1093]: info: Oct 26 13:22:08 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:08 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:08 volumio volumio[1093]: info: Oct 26 13:22:08 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand status took 5 milliseconds Oct 26 13:22:08 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:08 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:08 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:08 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:08 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"36-10Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/36-10Hz.dsf","trackType":"dsf"} Oct 26 13:22:08 volumio volumio[1093]: verbose: CURRENT POSITION 35 Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus stop Oct 26 13:22:08 volumio volumio[1093]: info: ------------------------------ 13ms Oct 26 13:22:08 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 13:22:08 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:08 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:08 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:08 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:08 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"36-10Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/36-10Hz.dsf","trackType":"dsf"} Oct 26 13:22:08 volumio volumio[1093]: verbose: CURRENT POSITION 35 Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Oct 26 13:22:08 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:08 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:08 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:08 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:08 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:08 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:08 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:08 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:08 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:08 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:08 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:08 volumio volumio[1093]: info: ------------------------------ 42ms Oct 26 13:22:13 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:13 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:13 volumio volumio[1093]: info: Prefetching next song Oct 26 13:22:13 volumio volumio[1093]: info: DOING PREFETCH IN MPD Oct 26 13:22:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf" Oct 26 13:22:13 volumio volumio[1093]: info: Oct 26 13:22:13 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:13 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:13 volumio volumio[1093]: info: sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf" took 2 milliseconds Oct 26 13:22:13 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 26 13:22:13 volumio volumio[1093]: info: ------------------------------ 3ms Oct 26 13:22:13 volumio volumio[1093]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 26 13:22:17 volumio volumio[1093]: info: Oct 26 13:22:17 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:17 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:17 volumio volumio[1093]: info: Oct 26 13:22:17 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:17 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:17 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:17 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:17 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Oct 26 13:22:17 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:17 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:17 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 13:22:17 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:17 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:17 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:17 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:17 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":11,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"37-20Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/37-20Hz.dsf","trackType":"dsf"} Oct 26 13:22:17 volumio volumio[1093]: verbose: CURRENT POSITION 35 Oct 26 13:22:17 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:17 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Oct 26 13:22:17 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Oct 26 13:22:17 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:17 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:17 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:17 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:17 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:17 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:17 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:17 volumio volumio[1093]: info: CorePlayQueue::getTrack 35 Oct 26 13:22:17 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:17 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:17 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:17 volumio volumio[1093]: info: ------------------------------ 20ms Oct 26 13:22:17 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Oct 26 13:22:17 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:18 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:18 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:18 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:18 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:18 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:18 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:23 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:23 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:23 volumio volumio[1093]: info: Prefetching next song Oct 26 13:22:23 volumio volumio[1093]: info: DOING PREFETCH IN MPD Oct 26 13:22:23 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf" Oct 26 13:22:23 volumio volumio[1093]: info: Oct 26 13:22:23 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:23 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:23 volumio volumio[1093]: info: sendMpdCommand add "USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf" took 2 milliseconds Oct 26 13:22:23 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 26 13:22:23 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:23 volumio volumio[1093]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 26 13:22:28 volumio volumio[1093]: info: Oct 26 13:22:28 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Oct 26 13:22:28 volumio volumio[1093]: info: Ignoring MPD Status Update Oct 26 13:22:28 volumio volumio[1093]: info: Oct 26 13:22:28 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:28 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:28 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:28 volumio volumio[1093]: info: ------------------------------ 2ms Oct 26 13:22:28 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Oct 26 13:22:28 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:28 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:28 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 13:22:28 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:28 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:28 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:28 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:28 volumio volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"38-25Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf","trackType":"dsf"} Oct 26 13:22:28 volumio volumio[1093]: verbose: CURRENT POSITION 36 Oct 26 13:22:28 volumio volumio[1093]: info: CoreStateMachine::syncState stateService play Oct 26 13:22:28 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Oct 26 13:22:28 volumio volumio[1093]: info: Received an update from plugin. extracting info from payload Oct 26 13:22:28 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:28 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:28 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:28 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:28 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:28 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:28 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:28 volumio volumio[1093]: info: CorePlayQueue::getTrack 36 Oct 26 13:22:28 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:28 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:28 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:28 volumio volumio[1093]: info: ------------------------------ 20ms Oct 26 13:22:28 volumio volumio[1093]: info: CoreStateMachine::startPlaybackTimer Oct 26 13:22:28 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:28 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:28 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:28 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:28 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:28 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:28 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:32 volumio volumio[1093]: info: CoreCommandRouter::volumioPause Oct 26 13:22:32 volumio volumio[1093]: info: CoreStateMachine::pause Oct 26 13:22:32 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Oct 26 13:22:32 volumio volumio[1093]: info: CoreStateMachine::servicePause Oct 26 13:22:32 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:32 volumio volumio[1093]: info: CoreCommandRouter::servicePause Oct 26 13:22:32 volumio volumio[1093]: info: ControllerMpd::pause Oct 26 13:22:32 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 13:22:32 volumio volumio[1093]: info: Oct 26 13:22:32 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:22:32 volumio volumio[1093]: info: sendMpdCommand pause took 2 milliseconds Oct 26 13:22:32 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:22:32 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:22:32 volumio volumio[1093]: info: sendMpdCommand status took 1 milliseconds Oct 26 13:22:32 volumio volumio[1093]: verbose: ControllerMpd::parseState Oct 26 13:22:32 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 13:22:32 volumio volumio[1093]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 26 13:22:32 volumio volumio[1093]: verbose: ControllerMpd::parseTrackInfo Oct 26 13:22:32 volumio volumio[1093]: info: ControllerMpd::pushState Oct 26 13:22:32 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Oct 26 13:22:32 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:32 volumio volumio[1093]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4773,"duration":10,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"38-25Hz","artist":"Test Disc","album":"Audiophile Hi-Res System Test - Great Sampling Tracks Included","uri":"USB/Elements/1. 24BIT HDTRACK/Music 24bit-352kHz/Audiophile Hi-Res System Test - Great Sampling Tracks Included DSD 5.6MHz/38-25Hz.dsf","trackType":"dsf"} Oct 26 13:22:32 volumio volumio[1093]: verbose: CURRENT POSITION 37 Oct 26 13:22:32 volumio volumio[1093]: info: CoreStateMachine::syncState stateService pause Oct 26 13:22:32 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Oct 26 13:22:32 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:22:32 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:22:32 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:22:32 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:22:32 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:22:32 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:22:32 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Oct 26 13:22:32 volumio volumio[1093]: info: ------------------------------ 12ms Oct 26 13:22:59 volumio volumio[1093]: info: CoreCommandRouter::volumioGetState Oct 26 13:22:59 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:23:01 volumio volumio[1093]: info: Executing endpoint metavolumio Oct 26 13:23:01 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 13:23:01 volumio volumio[1093]: info: Executing endpoint metavolumio Oct 26 13:23:01 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 13:23:01 volumio volumio[1093]: info: Executing endpoint metavolumio Oct 26 13:23:01 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 26 13:23:16 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 13:23:16 volumio volumio[1093]: info: CURURI: music-library/USB/Elements/$RECYCLE.BIN Oct 26 13:23:16 volumio volumio[1093]: info: Preload queue cleared Oct 26 13:23:18 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 13:23:18 volumio volumio[1093]: info: CURURI: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001 Oct 26 13:23:18 volumio volumio[1093]: info: Preload queue cleared Oct 26 13:23:18 volumio volumio[1093]: info: Preloading song: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R4Q40QR.mkv Oct 26 13:23:18 volumio volumio[1093]: info: Preloading song: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R96FAVD.avi Oct 26 13:23:18 volumio volumio[1093]: info: Preloading song: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RBOZHN8.mkv Oct 26 13:23:18 volumio volumio[1093]: info: Preloading song: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RSUB6CL.mkv Oct 26 13:23:18 volumio volumio[1093]: info: Preloading song: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RY2Y3TI.avi Oct 26 13:23:18 volumio volumio[1093]: info: Preloading song: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RYOJQ68.mp4 Oct 26 13:23:18 volumio volumio[1093]: info: Exploding uri music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R4Q40QR.mkv in service mpd Oct 26 13:23:18 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24R4Q40QR.mkv&metadata=false Oct 26 13:23:18 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R4Q40QR.mkv Oct 26 13:23:18 volumio volumio[1093]: info: Exploding uri music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R96FAVD.avi in service mpd Oct 26 13:23:18 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24R96FAVD.avi&metadata=false Oct 26 13:23:18 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R96FAVD.avi Oct 26 13:23:18 volumio volumio[1093]: info: Exploding uri music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RBOZHN8.mkv in service mpd Oct 26 13:23:18 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24RBOZHN8.mkv&metadata=false Oct 26 13:23:18 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RBOZHN8.mkv Oct 26 13:23:18 volumio volumio[1093]: info: Exploding uri music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RSUB6CL.mkv in service mpd Oct 26 13:23:18 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24RSUB6CL.mkv&metadata=false Oct 26 13:23:18 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RSUB6CL.mkv Oct 26 13:23:18 volumio volumio[1093]: info: Exploding uri music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RY2Y3TI.avi in service mpd Oct 26 13:23:18 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24RY2Y3TI.avi&metadata=false Oct 26 13:23:18 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RY2Y3TI.avi Oct 26 13:23:18 volumio volumio[1093]: info: Exploding uri music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RYOJQ68.mp4 in service mpd Oct 26 13:23:18 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24RYOJQ68.mp4&metadata=false Oct 26 13:23:18 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RYOJQ68.mp4 Oct 26 13:23:23 volumio volumio[1093]: info: Preload queue cleared Oct 26 13:23:23 volumio volumio[1093]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::ClearQueue Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::stop Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::updateTrackBlock Oct 26 13:23:23 volumio volumio[1093]: info: CorePlayQueue::getTrackBlock Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::stPlaybackTimer Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::pushState Oct 26 13:23:23 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:23:23 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:23:23 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Oct 26 13:23:23 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:23:23 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::serviceStop Oct 26 13:23:23 volumio volumio[1093]: info: CorePlayQueue::getTrack 37 Oct 26 13:23:23 volumio volumio[1093]: info: CoreCommandRouter::serviceStop Oct 26 13:23:23 volumio volumio[1093]: info: ControllerMpd::stop Oct 26 13:23:23 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 13:23:23 volumio volumio[1093]: info: CorePlayQueue::clearPlayQueue Oct 26 13:23:23 volumio volumio[1093]: info: CorePlayQueue::saveQueue Oct 26 13:23:23 volumio volumio[1093]: info: CoreCommandRouter::volumioPushQueue Oct 26 13:23:23 volumio volumio[1093]: info: CoreStateMachine::addQueueItems Oct 26 13:23:23 volumio volumio[1093]: info: CorePlayQueue::addQueueItems Oct 26 13:23:23 volumio volumio[1093]: info: Preload queue cleared Oct 26 13:23:23 volumio volumio[1093]: info: Adding Item to queue: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R4Q40QR.mkv Oct 26 13:23:23 volumio volumio[1093]: info: Using cached record of: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R4Q40QR.mkv Oct 26 13:23:23 volumio volumio[1093]: info: Adding Item to queue: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R96FAVD.avi Oct 26 13:23:23 volumio volumio[1093]: info: Using cached record of: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$R96FAVD.avi Oct 26 13:23:23 volumio volumio[1093]: info: Adding Item to queue: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RBOZHN8.mkv Oct 26 13:23:23 volumio volumio[1093]: info: Using cached record of: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RBOZHN8.mkv Oct 26 13:23:23 volumio volumio[1093]: info: Adding Item to queue: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RSUB6CL.mkv Oct 26 13:23:23 volumio volumio[1093]: info: Using cached record of: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RSUB6CL.mkv Oct 26 13:23:23 volumio volumio[1093]: info: Adding Item to queue: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RY2Y3TI.avi Oct 26 13:23:23 volumio volumio[1093]: info: Using cached record of: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RY2Y3TI.avi Oct 26 13:23:23 volumio volumio[1093]: info: Adding Item to queue: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RYOJQ68.mp4 Oct 26 13:23:23 volumio volumio[1093]: info: Using cached record of: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RYOJQ68.mp4 Oct 26 13:23:23 volumio volumio[1093]: info: Adding Item to queue: music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RAALRPQ Oct 26 13:23:23 volumio volumio[1093]: info: Exploding uri music-library/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RAALRPQ in service mpd Oct 26 13:23:40 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 13:23:40 volumio volumio[1093]: info: CURURI: music-library/USB/Elements/1. 24BIT HDTRACK Oct 26 13:23:40 volumio volumio[1093]: info: Oct 26 13:23:40 volumio volumio[1093]: ---------------------------- MPD announces state update: player Oct 26 13:23:41 volumio volumio[1093]: info: sendMpdCommand stop took 17977 milliseconds Oct 26 13:23:41 volumio volumio[1093]: info: ControllerMpd::getState Oct 26 13:23:41 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.104 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 26 13:23:41 volumio volumio[1093]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 13; SM-T970 Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 26 13:23:42 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=Liou%20Cch-sin/Problem%20tri%20teles/extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24RAALRPQ%2F!!!!!!!!!OSOBN%C3%8D%2F1111111111111111KNIHA%20Milan%2Fproblem-tri-teles.zip&metadata=false Oct 26 13:23:42 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RAALRPQ/!!!!!!!!!OSOBNÍ/1111111111111111KNIHA Milan/problem-tri-teles.zip Oct 26 13:23:42 volumio volumio[1093]: info: ALBUMART /albumart?cacheid=478&web=Liou%20Cch-sin/Problem%20tri%20teles/extralarge&path=%2Fmnt%2FUSB%2FElements%2F%24RECYCLE.BIN%2FS-1-5-21-3047630884-2069883771-14584520-1001%2F%24RAALRPQ%2F!!!!!!!!!OSOBN%C3%8D%2F1111111111111111KNIHA%20Milan%2Fproblem-tri-teles.zip&metadata=false Oct 26 13:23:42 volumio volumio[1093]: info: URI /mnt/USB/Elements/$RECYCLE.BIN/S-1-5-21-3047630884-2069883771-14584520-1001/$RAALRPQ/!!!!!!!!!OSOBNÍ/1111111111111111KNIHA Milan/problem-tri-teles.zip Oct 26 13:23:42 volumio volumio[1093]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 13:23:42 volumio volumio[1093]: Error: Unable to resolve or reject the same promise twice Oct 26 13:23:42 volumio volumio[1093]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 26 13:23:42 volumio volumio[1093]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 26 13:23:42 volumio volumio[1093]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 26 13:23:42 volumio volumio[1093]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 26 13:23:42 volumio volumio[1093]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 26 13:23:42 volumio volumio[1093]: at Socket.emit (events.js:315:20) Oct 26 13:23:42 volumio volumio[1093]: at addChunk (internal/streams/readable.js:309:12) Oct 26 13:23:42 volumio volumio[1093]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 26 13:23:42 volumio volumio[1093]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 26 13:23:42 volumio volumio[1093]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 26 13:23:42 volumio volumio[1093]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 13:23:43 volumio sudo[24478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 13:22 Oct 26 13:23:43 volumio sudo[24478]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"