-- Logs begin at Sat 2024-08-24 14:09:46 MSK, end at Sun 2024-08-25 17:24:42 MSK. -- Aug 25 17:23:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 17:23:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 17:23:02 volumio volumio[804]: info: Discovery: Getting this device information Aug 25 17:23:02 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Aug 25 17:23:02 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 17:23:32 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 17:23:32 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 17:23:32 volumio volumio[804]: info: Discovery: Getting this device information Aug 25 17:23:32 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Aug 25 17:23:32 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:32 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 17:23:33 volumio volumio[804]: info: VolumeController::SetAlsaVolume99 Aug 25 17:23:33 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:33 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:33 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:33 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:34 volumio volumio[804]: info: VolumeController::SetAlsaVolume65 Aug 25 17:23:34 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:34 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:34 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:34 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::volumioPause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::servicePause Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::servicePause Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::pause Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand pause Aug 25 17:23:38 volumio volumio[804]: info: Aug 25 17:23:38 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand pause took 23 milliseconds Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:38 volumio volumio[804]: info: Aug 25 17:23:38 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:38 volumio volumio[804]: info: Aug 25 17:23:38 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:38 volumio volumio[804]: info: Aug 25 17:23:38 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand status took 4 milliseconds Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand status took 4 milliseconds Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand status took 3 milliseconds Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand status took 2 milliseconds Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 25 17:23:38 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:38 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79095,"duration":120,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Charles_A Fool For You - excerpt (from SL1023A)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf","trackType":"dsf"} Aug 25 17:23:38 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79095,"duration":120,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Charles_A Fool For You - excerpt (from SL1023A)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf","trackType":"dsf"} Aug 25 17:23:38 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79095,"duration":120,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Charles_A Fool For You - excerpt (from SL1023A)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf","trackType":"dsf"} Aug 25 17:23:38 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:23:38 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79095,"duration":120,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Charles_A Fool For You - excerpt (from SL1023A)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf","trackType":"dsf"} Aug 25 17:23:38 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:38 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:38 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:38 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:23:38 volumio volumio[804]: info: ------------------------------ 46ms Aug 25 17:23:38 volumio volumio[804]: info: ------------------------------ 45ms Aug 25 17:23:38 volumio volumio[804]: info: ------------------------------ 44ms Aug 25 17:23:38 volumio volumio[804]: info: ------------------------------ 43ms Aug 25 17:23:38 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 25 17:23:38 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 25 17:23:38 volumio volumio[804]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 25 17:23:39 volumio volumio[804]: info: Preload queue cleared Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::ClearQueue Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::updateTrackBlock Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrackBlock Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::serviceStop Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::serviceStop Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::stop Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::clearPlayQueue Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::saveQueue Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::addQueueItems Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::addQueueItems Aug 25 17:23:39 volumio volumio[804]: info: Preload queue cleared Aug 25 17:23:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf Aug 25 17:23:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::saveQueue Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::updateTrackBlock Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrackBlock Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPlay Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::play index 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::play index undefined Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::startPlaybackTimer Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::clearAddPlayTracks USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 17:23:39 volumio volumio[804]: info: Aug 25 17:23:39 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:39 volumio volumio[804]: info: sendMpdCommand stop took 51 milliseconds Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:39 volumio volumio[804]: info: sendMpdCommand stop took 32 milliseconds Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 17:23:39 volumio volumio[804]: info: Aug 25 17:23:39 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:39 volumio volumio[804]: info: Aug 25 17:23:39 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:39 volumio volumio[804]: info: Aug 25 17:23:39 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:39 volumio volumio[804]: info: Aug 25 17:23:39 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:39 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:39 volumio volumio[804]: info: sendMpdCommand status took 17 milliseconds Aug 25 17:23:39 volumio volumio[804]: info: sendMpdCommand clear took 15 milliseconds Aug 25 17:23:39 volumio volumio[804]: info: sendMpdCommand status took 12 milliseconds Aug 25 17:23:39 volumio volumio[804]: info: sendMpdCommand status took 10 milliseconds Aug 25 17:23:39 volumio volumio[804]: info: sendMpdCommand status took 7 milliseconds Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand add "USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf" Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:39 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: 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} Aug 25 17:23:39 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::syncState stateService stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: No code Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: 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} Aug 25 17:23:39 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::syncState stateService stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: No code Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: 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} Aug 25 17:23:39 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::syncState stateService stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: No code Aug 25 17:23:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:39 volumio volumio[804]: info: ------------------------------ 77ms Aug 25 17:23:39 volumio volumio[804]: info: ------------------------------ 77ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 76ms Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:40 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:40 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:40 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:40 volumio volumio[804]: error: updateQueue error: null Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:40 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 95ms Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 88 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand add "USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf" took 89 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 22ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 21ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 19ms Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand play Aug 25 17:23:40 volumio volumio[804]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 117ms Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:40 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:40 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:23:40 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 86ms Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand play took 79 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 78ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 70ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 69ms Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 17:23:40 volumio volumio[804]: info: [now-playing] Fetch metadata: {"type":"song","name":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler"} Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 207 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: info: Aug 25 17:23:40 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 218 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 215 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 203 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 12 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 7 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 7 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand status took 6 milliseconds Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 232ms Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 25 17:23:40 volumio volumio[804]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:23:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:23:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:23:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:23:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:23:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:23:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":280,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:23:40 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:23:40 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:40 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:40 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 360ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 362ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 360ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 162ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 156ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 156ms Aug 25 17:23:40 volumio volumio[804]: info: ------------------------------ 153ms Aug 25 17:23:44 volumio volumio[804]: info: VolumeController::SetAlsaVolume74 Aug 25 17:23:44 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:44 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:44 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:23:44 volumio volumio[804]: info: VolumeController::SetAlsaVolume91 Aug 25 17:23:44 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:23:44 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:23:44 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:23:44 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 17:24:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 17:24:02 volumio volumio[804]: info: Discovery: Getting this device information Aug 25 17:24:02 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Aug 25 17:24:02 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::volumioPause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::servicePause Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::servicePause Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::pause Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand pause Aug 25 17:24:07 volumio volumio[804]: info: Aug 25 17:24:07 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand pause took 2 milliseconds Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:07 volumio volumio[804]: info: Aug 25 17:24:07 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:07 volumio volumio[804]: info: Aug 25 17:24:07 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:07 volumio volumio[804]: info: Aug 25 17:24:07 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand status took 6 milliseconds Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand status took 5 milliseconds Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand status took 4 milliseconds Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand status took 2 milliseconds Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 25 17:24:07 volumio volumio[804]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:07 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27810,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:24:07 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27810,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:24:07 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27810,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:24:07 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:24:07 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27810,"duration":164,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Polence_Counting Frogs - excerpt (from JL003)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf","trackType":"dsf"} Aug 25 17:24:07 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState stateService pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:07 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:07 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:07 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:24:07 volumio volumio[804]: info: ------------------------------ 35ms Aug 25 17:24:07 volumio volumio[804]: info: ------------------------------ 34ms Aug 25 17:24:07 volumio volumio[804]: info: ------------------------------ 33ms Aug 25 17:24:07 volumio volumio[804]: info: ------------------------------ 33ms Aug 25 17:24:07 volumio volumio[804]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 25 17:24:11 volumio volumio[804]: info: Preload queue cleared Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::ClearQueue Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::updateTrackBlock Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrackBlock Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::serviceStop Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::serviceStop Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::stop Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::clearPlayQueue Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::saveQueue Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::addQueueItems Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::addQueueItems Aug 25 17:24:11 volumio volumio[804]: info: Preload queue cleared Aug 25 17:24:11 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf Aug 25 17:24:11 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::saveQueue Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::updateTrackBlock Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrackBlock Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPlay Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::play index 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::play index undefined Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::startPlaybackTimer Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::clearAddPlayTracks USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand stop took 43 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand stop took 29 milliseconds Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand status took 14 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand clear took 13 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand status took 8 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand status took 7 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand status took 7 milliseconds Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand add "USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf" Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: 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} Aug 25 17:24:11 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::syncState stateService stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: No code Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: 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} Aug 25 17:24:11 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::syncState stateService stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: No code Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: 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} Aug 25 17:24:11 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::syncState stateService stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: No code Aug 25 17:24:11 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 76ms Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 75ms Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 75ms Aug 25 17:24:11 volumio volumio[804]: error: updateQueue error: null Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 84ms Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand playlistinfo took 80 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand add "USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf" took 79 milliseconds Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand play Aug 25 17:24:11 volumio volumio[804]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 104ms Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 243ms Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand play took 239 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 234ms Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 233ms Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 232ms Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces system playlist update Aug 25 17:24:11 volumio volumio[804]: info: Ignoring MPD Status Update Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 17:24:11 volumio volumio[804]: info: [now-playing] Fetch metadata: {"type":"song","name":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler"} Aug 25 17:24:11 volumio volumio[804]: info: Aug 25 17:24:11 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand status took 74 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 73ms Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 70ms Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand status took 68 milliseconds Aug 25 17:24:11 volumio volumio[804]: info: ------------------------------ 67ms Aug 25 17:24:11 volumio volumio[804]: info: sendMpdCommand status took 66 milliseconds Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:11 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:12 volumio volumio[804]: info: Aug 25 17:24:12 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:12 volumio volumio[804]: info: Aug 25 17:24:12 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:12 volumio volumio[804]: info: Aug 25 17:24:12 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::getState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand status took 19 milliseconds Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand status took 15 milliseconds Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":249,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf","trackType":"dsf"} Aug 25 17:24:12 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":249,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf","trackType":"dsf"} Aug 25 17:24:12 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:24:12 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":249,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf","trackType":"dsf"} Aug 25 17:24:12 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:24:12 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: ------------------------------ 123ms Aug 25 17:24:12 volumio volumio[804]: info: ------------------------------ 124ms Aug 25 17:24:12 volumio volumio[804]: info: ------------------------------ 122ms Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand status took 49 milliseconds Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand status took 49 milliseconds Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand playlistinfo took 36 milliseconds Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseState Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":417,"duration":249,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf","trackType":"dsf"} Aug 25 17:24:12 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:24:12 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":417,"duration":249,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf","trackType":"dsf"} Aug 25 17:24:12 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:24:12 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: ------------------------------ 96ms Aug 25 17:24:12 volumio volumio[804]: info: ------------------------------ 90ms Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand playlistinfo took 69 milliseconds Aug 25 17:24:12 volumio volumio[804]: info: sendMpdCommand playlistinfo took 70 milliseconds Aug 25 17:24:12 volumio volumio[804]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":417,"duration":249,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf","trackType":"dsf"} Aug 25 17:24:12 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:24:12 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: ControllerMpd::pushState Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::servicePushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: verbose: STATE SERVICE {"status":"play","position":0,"seek":417,"duration":249,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Ryan_Firefly (from VDM03855032)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf","trackType":"dsf"} Aug 25 17:24:12 volumio volumio[804]: verbose: CURRENT POSITION 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:24:12 volumio volumio[804]: info: Received an update from plugin. extracting info from payload Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:12 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:12 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:12 volumio volumio[804]: info: ------------------------------ 146ms Aug 25 17:24:12 volumio volumio[804]: info: ------------------------------ 145ms Aug 25 17:24:32 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 17:24:32 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 17:24:32 volumio volumio[804]: info: Discovery: Getting this device information Aug 25 17:24:32 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Aug 25 17:24:32 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:32 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 17:24:39 volumio volumio[804]: info: Preload queue cleared Aug 25 17:24:39 volumio volumio[804]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::ClearQueue Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::stop Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::updateTrackBlock Aug 25 17:24:39 volumio volumio[804]: info: CorePlayQueue::getTrackBlock Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::pushState Aug 25 17:24:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:39 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:24:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::serviceStop Aug 25 17:24:39 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Aug 25 17:24:39 volumio volumio[804]: info: CoreCommandRouter::serviceStop Aug 25 17:24:39 volumio volumio[804]: info: ControllerMpd::stop Aug 25 17:24:39 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 17:24:39 volumio volumio[804]: info: CorePlayQueue::clearPlayQueue Aug 25 17:24:39 volumio volumio[804]: info: CorePlayQueue::saveQueue Aug 25 17:24:39 volumio volumio[804]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:24:39 volumio volumio[804]: info: CoreStateMachine::addQueueItems Aug 25 17:24:39 volumio volumio[804]: info: CorePlayQueue::addQueueItems Aug 25 17:24:39 volumio volumio[804]: info: Preload queue cleared Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/1_Introduction-and-Classical-Choices_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/1_Introduction-and-Classical-Choices_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/2_Beethoven_Symphony-no-5-Allegro-con-Brio-excerpt-from-FR718SACD_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/2_Beethoven_Symphony-no-5-Allegro-con-Brio-excerpt-from-FR718SACD_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/3_Chopin_Etude-in-F-major-op-10-excerpt-from-EUDDR1703_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/3_Chopin_Etude-in-F-major-op-10-excerpt-from-EUDDR1703_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/4_Mendelssohn_String-Quartet-no-2-in-a-minor-Intermezzo-excerpt-from-39317_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/4_Mendelssohn_String-Quartet-no-2-in-a-minor-Intermezzo-excerpt-from-39317_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/5_Shostakovich_Symphony-no-5-in-D-minor-allegretto-excerpt-from-FR724SACD_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/5_Shostakovich_Symphony-no-5-in-D-minor-allegretto-excerpt-from-FR724SACD_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/6_Barrios_La-Catedral-Preludio-excerpt-from-EUDDR1801_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/6_Barrios_La-Catedral-Preludio-excerpt-from-EUDDR1801_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/7_Bach_Suite-No-1-I-Prelude-excerpt-from-NC15003_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/7_Bach_Suite-No-1-I-Prelude-excerpt-from-NC15003_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/8_Nordheim_Music-to-Two-Fragments-by-Shelley-II-No-Music-from-LWC1134_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/8_Nordheim_Music-to-Two-Fragments-by-Shelley-II-No-Music-from-LWC1134_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/9_Traditional_Hijaz-Improvisation-excerpt-from-TTK0008_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/9_Traditional_Hijaz-Improvisation-excerpt-from-TTK0008_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/10_Mahler_Symphony-no-3-Langsam-excerpt-from-38817_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/10_Mahler_Symphony-no-3-Langsam-excerpt-from-38817_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/11_Introduction-to-Pop-Jazz-Rock-Tracks_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/11_Introduction-to-Pop-Jazz-Rock-Tracks_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/14_Skidmore_The-Impossible-Sum-excerpt-from-TTK0015_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/14_Skidmore_The-Impossible-Sum-excerpt-from-TTK0015_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/15_Fain_Secret-Love-excerpt-from-YAR65006DSD_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/15_Fain_Secret-Love-excerpt-from-YAR65006DSD_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/17_Newman_Short-People-from-JL002_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/17_Newman_Short-People-from-JL002_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/19_Rodgers_The-Sweetest-Sounds-from-JL009_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/19_Rodgers_The-Sweetest-Sounds-from-JL009_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/20_Kohashi_Blues-for-Maya-excerpt-from-TTK0010_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/20_Kohashi_Blues-for-Maya-excerpt-from-TTK0010_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/21_Previn_Youre-Gonna-Hear-from-Me-from-2XHDRE1069_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/21_Previn_Youre-Gonna-Hear-from-Me-from-2XHDRE1069_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/22_Closing-remarks_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/22_Closing-remarks_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/23_Vivaldi-The-Four-Seasons-Winter-II-Largo-from-40318_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Using cached record of: music-library/USB/SONY_USB/DSD/23_Vivaldi-The-Four-Seasons-Winter-II-Largo-from-40318_stereo.dsf Aug 25 17:24:39 volumio volumio[804]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip Aug 25 17:24:39 volumio volumio[804]: info: Exploding uri music-library/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip in service mpd Aug 25 17:24:39 volumio volumio[804]: info: Aug 25 17:24:39 volumio volumio[804]: ---------------------------- MPD announces state update: player Aug 25 17:24:39 volumio volumio[804]: info: ALBUMART /albumart?cacheid=635&web=//extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip&metadata=false Aug 25 17:24:39 volumio volumio[804]: info: URI /mnt/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip Aug 25 17:24:39 volumio volumio[804]: info: ALBUMART /albumart?cacheid=635&web=//extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip&metadata=false Aug 25 17:24:39 volumio volumio[804]: info: URI /mnt/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip Aug 25 17:24:39 volumio volumio[804]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 17:24:39 volumio volumio[804]: Error: Unable to resolve or reject the same promise twice Aug 25 17:24:39 volumio volumio[804]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 25 17:24:39 volumio volumio[804]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 25 17:24:39 volumio volumio[804]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 25 17:24:39 volumio volumio[804]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 25 17:24:39 volumio volumio[804]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 25 17:24:39 volumio volumio[804]: at Socket.emit (events.js:315:20) Aug 25 17:24:39 volumio volumio[804]: at addChunk (internal/streams/readable.js:309:12) Aug 25 17:24:39 volumio volumio[804]: at readableAddChunk (internal/streams/readable.js:280:11) Aug 25 17:24:39 volumio volumio[804]: at Socket.Readable.push (internal/streams/readable.js:223:10) Aug 25 17:24:39 volumio volumio[804]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 25 17:24:39 volumio volumio[804]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 17:24:42 volumio sudo[2990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 17:23 Aug 25 17:24:42 volumio sudo[2990]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"