-- Logs begin at Thu 2021-09-30 20:42:09 UTC, end at Thu 2025-03-13 09:27:24 UTC. -- Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:03 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 2 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 2 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:03 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:03 volumio volumio[23246]: info: Mar 13 09:26:03 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand stop took 82 milliseconds Mar 13 09:26:03 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/04.We've Only Just Begun.dsf Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand status took 7 milliseconds Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand stop took 4 milliseconds Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:03 volumio volumio[23246]: info: Mar 13 09:26:03 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:03 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand clear took 4 milliseconds Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/04.We've Only Just Begun.dsf" Mar 13 09:26:03 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"There's A Kind Of Hush","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/03.There's A Kind Of Hush.dsf","trackType":"dsf"} Mar 13 09:26:03 volumio volumio[23246]: verbose: CURRENT POSITION 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::syncState stateService stop Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:03 volumio volumio[23246]: info: No code Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:03 volumio volumio[23246]: info: ------------------------------ 34ms Mar 13 09:26:03 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:03 volumio volumio[23246]: info: Mar 13 09:26:03 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:03 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:03 volumio volumio[23246]: info: ------------------------------ 25ms Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/04.We've Only Just Begun.dsf" took 22 milliseconds Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:03 volumio volumio[23246]: info: ------------------------------ 10ms Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand play took 9 milliseconds Mar 13 09:26:03 volumio volumio[23246]: info: Mar 13 09:26:03 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:03 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:03 volumio volumio[23246]: info: Mar 13 09:26:03 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:03 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand status took 5 milliseconds Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:03 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"We've Only Just Begun","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/04.We've Only Just Begun.dsf","trackType":"dsf"} Mar 13 09:26:03 volumio volumio[23246]: verbose: CURRENT POSITION 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:03 volumio volumio[23246]: info: ------------------------------ 25ms Mar 13 09:26:03 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 13 09:26:03 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:03 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":354,"duration":244,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"We've Only Just Begun","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/04.We've Only Just Begun.dsf","trackType":"dsf"} Mar 13 09:26:03 volumio volumio[23246]: verbose: CURRENT POSITION 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:03 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:03 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:03 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:03 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:03 volumio volumio[23246]: info: ------------------------------ 50ms Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:08 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 3 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:08 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:08 volumio volumio[23246]: info: Mar 13 09:26:08 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand stop took 71 milliseconds Mar 13 09:26:08 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/05.Please Mr Postman.dsf Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand status took 7 milliseconds Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand stop took 5 milliseconds Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:08 volumio volumio[23246]: info: Mar 13 09:26:08 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:08 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand clear took 4 milliseconds Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/05.Please Mr Postman.dsf" Mar 13 09:26:08 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"We've Only Just Begun","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/04.We've Only Just Begun.dsf","trackType":"dsf"} Mar 13 09:26:08 volumio volumio[23246]: verbose: CURRENT POSITION 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::syncState stateService stop Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:08 volumio volumio[23246]: info: No code Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:08 volumio volumio[23246]: info: ------------------------------ 39ms Mar 13 09:26:08 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:08 volumio volumio[23246]: info: Mar 13 09:26:08 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:08 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:08 volumio volumio[23246]: info: ------------------------------ 29ms Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/05.Please Mr Postman.dsf" took 28 milliseconds Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:08 volumio volumio[23246]: info: ------------------------------ 9ms Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand play took 9 milliseconds Mar 13 09:26:08 volumio volumio[23246]: info: Mar 13 09:26:08 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:08 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:08 volumio volumio[23246]: info: Mar 13 09:26:08 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:08 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:08 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":63,"duration":217,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Please Mr Postman","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/05.Please Mr Postman.dsf","trackType":"dsf"} Mar 13 09:26:08 volumio volumio[23246]: verbose: CURRENT POSITION 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:08 volumio volumio[23246]: info: ------------------------------ 24ms Mar 13 09:26:08 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 13 09:26:08 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:08 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":336,"duration":217,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Please Mr Postman","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/05.Please Mr Postman.dsf","trackType":"dsf"} Mar 13 09:26:08 volumio volumio[23246]: verbose: CURRENT POSITION 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:08 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:08 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:08 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:08 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:08 volumio volumio[23246]: info: ------------------------------ 41ms Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:10 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 4 Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:10 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand stop took 96 milliseconds Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/06.I Won't Last A Day Without You.dsf Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:10 volumio volumio[23246]: info: Mar 13 09:26:10 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:10 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand stop took 5 milliseconds Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:10 volumio volumio[23246]: info: Mar 13 09:26:10 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:10 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand clear took 3 milliseconds Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/06.I Won't Last A Day Without You.dsf" Mar 13 09:26:10 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:10 volumio volumio[23246]: info: Mar 13 09:26:10 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:10 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:10 volumio volumio[23246]: info: ------------------------------ 5ms Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/06.I Won't Last A Day Without You.dsf" took 4 milliseconds Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:10 volumio volumio[23246]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 13 09:26:10 volumio volumio[23246]: info: ------------------------------ 13ms Mar 13 09:26:10 volumio volumio[23246]: info: ------------------------------ 6ms Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand play took 4 milliseconds Mar 13 09:26:10 volumio volumio[23246]: info: Mar 13 09:26:10 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:10 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:10 volumio volumio[23246]: info: Mar 13 09:26:10 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:10 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand status took 5 milliseconds Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:10 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:10 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":87,"duration":276,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"I Won't Last A Day Without You","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/06.I Won't Last A Day Without You.dsf","trackType":"dsf"} Mar 13 09:26:10 volumio volumio[23246]: verbose: CURRENT POSITION 5 Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:10 volumio volumio[23246]: info: ------------------------------ 26ms Mar 13 09:26:10 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 13 09:26:10 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:10 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:10 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":276,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"I Won't Last A Day Without You","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/06.I Won't Last A Day Without You.dsf","trackType":"dsf"} Mar 13 09:26:10 volumio volumio[23246]: verbose: CURRENT POSITION 5 Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:10 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:10 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:10 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:10 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:10 volumio volumio[23246]: info: ------------------------------ 44ms Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:12 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 5 Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:12 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand stop took 87 milliseconds Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/07.Sing.dsf Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:12 volumio volumio[23246]: info: Mar 13 09:26:12 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:12 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand stop took 5 milliseconds Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:12 volumio volumio[23246]: info: Mar 13 09:26:12 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:12 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand clear took 3 milliseconds Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/07.Sing.dsf" Mar 13 09:26:12 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:12 volumio volumio[23246]: info: Mar 13 09:26:12 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:12 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:12 volumio volumio[23246]: info: ------------------------------ 5ms Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/07.Sing.dsf" took 4 milliseconds Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:12 volumio volumio[23246]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 13 09:26:12 volumio volumio[23246]: info: ------------------------------ 12ms Mar 13 09:26:12 volumio volumio[23246]: info: ------------------------------ 5ms Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand play took 3 milliseconds Mar 13 09:26:12 volumio volumio[23246]: info: Mar 13 09:26:12 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:12 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:12 volumio volumio[23246]: info: Mar 13 09:26:12 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:12 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand status took 5 milliseconds Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand status took 5 milliseconds Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:12 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:12 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116,"duration":192,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Sing","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/07.Sing.dsf","trackType":"dsf"} Mar 13 09:26:12 volumio volumio[23246]: verbose: CURRENT POSITION 6 Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:12 volumio volumio[23246]: info: ------------------------------ 25ms Mar 13 09:26:12 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 13 09:26:12 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:12 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:12 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":325,"duration":192,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Sing","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/07.Sing.dsf","trackType":"dsf"} Mar 13 09:26:12 volumio volumio[23246]: verbose: CURRENT POSITION 6 Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:12 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:12 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:12 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:12 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:12 volumio volumio[23246]: info: ------------------------------ 45ms Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:14 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 6 Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:14 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand stop took 124 milliseconds Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/08.For All We Know.dsf Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:14 volumio volumio[23246]: info: Mar 13 09:26:14 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:14 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand stop took 5 milliseconds Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:14 volumio volumio[23246]: info: Mar 13 09:26:14 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:14 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand clear took 3 milliseconds Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/08.For All We Know.dsf" Mar 13 09:26:14 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:14 volumio volumio[23246]: info: Mar 13 09:26:14 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:14 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:14 volumio volumio[23246]: info: ------------------------------ 5ms Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/08.For All We Know.dsf" took 4 milliseconds Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:14 volumio volumio[23246]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 13 09:26:14 volumio volumio[23246]: info: ------------------------------ 12ms Mar 13 09:26:14 volumio volumio[23246]: info: ------------------------------ 5ms Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand play took 3 milliseconds Mar 13 09:26:14 volumio volumio[23246]: info: Mar 13 09:26:14 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:14 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:14 volumio volumio[23246]: info: Mar 13 09:26:14 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:14 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand status took 5 milliseconds Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:14 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:14 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":87,"duration":183,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"For All We Know","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/08.For All We Know.dsf","trackType":"dsf"} Mar 13 09:26:14 volumio volumio[23246]: verbose: CURRENT POSITION 7 Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:14 volumio volumio[23246]: info: ------------------------------ 25ms Mar 13 09:26:14 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 13 09:26:14 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:14 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:14 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":183,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"For All We Know","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/08.For All We Know.dsf","trackType":"dsf"} Mar 13 09:26:14 volumio volumio[23246]: verbose: CURRENT POSITION 7 Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:14 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:14 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:14 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:14 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:14 volumio volumio[23246]: info: ------------------------------ 42ms Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:17 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 7 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:17 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:17 volumio volumio[23246]: info: Mar 13 09:26:17 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand stop took 172 milliseconds Mar 13 09:26:17 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/09.Ticket To Ride.dsf Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand status took 8 milliseconds Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand stop took 6 milliseconds Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:17 volumio volumio[23246]: info: Mar 13 09:26:17 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:17 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand clear took 5 milliseconds Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/09.Ticket To Ride.dsf" Mar 13 09:26:17 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"For All We Know","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/08.For All We Know.dsf","trackType":"dsf"} Mar 13 09:26:17 volumio volumio[23246]: verbose: CURRENT POSITION 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::syncState stateService stop Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:17 volumio volumio[23246]: info: No code Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:17 volumio volumio[23246]: info: ------------------------------ 37ms Mar 13 09:26:17 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:17 volumio volumio[23246]: info: Mar 13 09:26:17 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:17 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:17 volumio volumio[23246]: info: ------------------------------ 24ms Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/09.Ticket To Ride.dsf" took 22 milliseconds Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:17 volumio volumio[23246]: info: ------------------------------ 13ms Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand play took 13 milliseconds Mar 13 09:26:17 volumio volumio[23246]: info: Mar 13 09:26:17 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:17 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:17 volumio volumio[23246]: info: Mar 13 09:26:17 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:17 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand status took 6 milliseconds Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:17 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Ticket To Ride","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/09.Ticket To Ride.dsf","trackType":"dsf"} Mar 13 09:26:17 volumio volumio[23246]: verbose: CURRENT POSITION 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:17 volumio volumio[23246]: info: ------------------------------ 26ms Mar 13 09:26:17 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 13 09:26:17 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:17 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":412,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Ticket To Ride","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/09.Ticket To Ride.dsf","trackType":"dsf"} Mar 13 09:26:17 volumio volumio[23246]: verbose: CURRENT POSITION 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:17 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:17 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:17 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:17 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:17 volumio volumio[23246]: info: ------------------------------ 43ms Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:18 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 8 Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:18 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand stop took 52 milliseconds Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/10.Top Of The World.dsf Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:18 volumio volumio[23246]: info: Mar 13 09:26:18 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:18 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand stop took 8 milliseconds Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:18 volumio volumio[23246]: info: Mar 13 09:26:18 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:18 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand clear took 3 milliseconds Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/10.Top Of The World.dsf" Mar 13 09:26:18 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:18 volumio volumio[23246]: info: Mar 13 09:26:18 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:18 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:18 volumio volumio[23246]: info: ------------------------------ 5ms Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/10.Top Of The World.dsf" took 3 milliseconds Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:18 volumio volumio[23246]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 13 09:26:18 volumio volumio[23246]: info: ------------------------------ 12ms Mar 13 09:26:18 volumio volumio[23246]: info: ------------------------------ 4ms Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand play took 2 milliseconds Mar 13 09:26:18 volumio volumio[23246]: info: Mar 13 09:26:18 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:18 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:18 volumio volumio[23246]: info: Mar 13 09:26:18 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:18 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:18 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:18 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133,"duration":241,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/10.Top Of The World.dsf","trackType":"dsf"} Mar 13 09:26:18 volumio volumio[23246]: verbose: CURRENT POSITION 9 Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:18 volumio volumio[23246]: info: ------------------------------ 25ms Mar 13 09:26:18 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 13 09:26:18 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:18 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:18 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":470,"duration":241,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"Top Of The World","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/10.Top Of The World.dsf","trackType":"dsf"} Mar 13 09:26:18 volumio volumio[23246]: verbose: CURRENT POSITION 9 Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:18 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:18 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:18 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:18 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:18 volumio volumio[23246]: info: ------------------------------ 43ms Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:22 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 9 Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:22 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:22 volumio volumio[23246]: info: Mar 13 09:26:22 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:22 volumio volumio[23246]: info: sendMpdCommand stop took 106 milliseconds Mar 13 09:26:22 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/11.It's Going To Take Some Time.dsf Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:22 volumio volumio[23246]: info: sendMpdCommand status took 15 milliseconds Mar 13 09:26:22 volumio volumio[23246]: info: sendMpdCommand stop took 13 milliseconds Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:22 volumio volumio[23246]: info: Mar 13 09:26:22 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:22 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:22 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 13 09:26:22 volumio volumio[23246]: info: sendMpdCommand clear took 5 milliseconds Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:22 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/11.It's Going To Take Some Time.dsf" Mar 13 09:26:22 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:22 volumio volumio[23246]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Top Of The World","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/10.Top Of The World.dsf","trackType":"dsf"} Mar 13 09:26:22 volumio volumio[23246]: verbose: CURRENT POSITION 10 Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::syncState stateService stop Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:22 volumio volumio[23246]: info: No code Mar 13 09:26:22 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:22 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:22 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:22 volumio volumio[23246]: info: ------------------------------ 49ms Mar 13 09:26:22 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:23 volumio volumio[23246]: info: Mar 13 09:26:23 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:23 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:23 volumio volumio[23246]: info: ------------------------------ 32ms Mar 13 09:26:23 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/11.It's Going To Take Some Time.dsf" took 28 milliseconds Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:23 volumio volumio[23246]: info: ------------------------------ 11ms Mar 13 09:26:23 volumio volumio[23246]: info: sendMpdCommand play took 9 milliseconds Mar 13 09:26:23 volumio volumio[23246]: info: Mar 13 09:26:23 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:23 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:23 volumio volumio[23246]: info: Mar 13 09:26:23 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:23 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:23 volumio volumio[23246]: info: sendMpdCommand status took 3 milliseconds Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:23 volumio volumio[23246]: info: sendMpdCommand status took 5 milliseconds Mar 13 09:26:23 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:23 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:23 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:23 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":150,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"It's Going To Take Some Time","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/11.It's Going To Take Some Time.dsf","trackType":"dsf"} Mar 13 09:26:23 volumio volumio[23246]: verbose: CURRENT POSITION 10 Mar 13 09:26:23 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:23 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:23 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:23 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:23 volumio volumio[23246]: info: ------------------------------ 31ms Mar 13 09:26:23 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 13 09:26:23 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:23 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:23 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:23 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":470,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"It's Going To Take Some Time","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/11.It's Going To Take Some Time.dsf","trackType":"dsf"} Mar 13 09:26:23 volumio volumio[23246]: verbose: CURRENT POSITION 10 Mar 13 09:26:23 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:23 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:23 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:23 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:23 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:23 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:23 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:23 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:23 volumio volumio[23246]: info: ------------------------------ 52ms Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::volumioNext Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::next Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:29 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 10 Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:29 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand stop took 58 milliseconds Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/12.The Rainbow Connection.dsf Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:29 volumio volumio[23246]: info: Mar 13 09:26:29 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:29 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand stop took 4 milliseconds Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:29 volumio volumio[23246]: info: Mar 13 09:26:29 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:29 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand status took 5 milliseconds Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand clear took 4 milliseconds Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/12.The Rainbow Connection.dsf" Mar 13 09:26:29 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:29 volumio volumio[23246]: info: Mar 13 09:26:29 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:29 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:29 volumio volumio[23246]: info: ------------------------------ 5ms Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/12.The Rainbow Connection.dsf" took 3 milliseconds Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:29 volumio volumio[23246]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 13 09:26:29 volumio volumio[23246]: info: ------------------------------ 12ms Mar 13 09:26:29 volumio volumio[23246]: info: ------------------------------ 4ms Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand play took 3 milliseconds Mar 13 09:26:29 volumio volumio[23246]: info: Mar 13 09:26:29 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:29 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:29 volumio volumio[23246]: info: Mar 13 09:26:29 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:29 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand status took 4 milliseconds Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:29 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:29 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"The Rainbow Connection","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/12.The Rainbow Connection.dsf","trackType":"dsf"} Mar 13 09:26:29 volumio volumio[23246]: verbose: CURRENT POSITION 11 Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:29 volumio volumio[23246]: info: ------------------------------ 24ms Mar 13 09:26:29 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 13 09:26:29 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:29 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:29 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":470,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"The Rainbow Connection","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/12.The Rainbow Connection.dsf","trackType":"dsf"} Mar 13 09:26:29 volumio volumio[23246]: verbose: CURRENT POSITION 11 Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:29 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:29 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:29 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:29 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:29 volumio volumio[23246]: info: ------------------------------ 42ms Mar 13 09:26:34 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 09:26:34 volumio volumio[23246]: info: CURURI: music-library Mar 13 09:26:34 volumio volumio[23246]: info: CoreCommandRouter::volumioGetQueue Mar 13 09:26:34 volumio volumio[23246]: info: CoreStateMachine::getQueue Mar 13 09:26:34 volumio volumio[23246]: info: CorePlayQueue::getQueue Mar 13 09:26:35 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 09:26:35 volumio volumio[23246]: info: CURURI: music-library/NAS Mar 13 09:26:36 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 09:26:36 volumio volumio[23246]: info: CURURI: music-library/NAS/sunservice Mar 13 09:26:42 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 09:26:42 volumio volumio[23246]: info: CURURI: music-library/NAS/sunservice/SONY 古典 Mar 13 09:26:49 volumio nmbd[965]: [2025/03/13 09:26:49.062828, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 13 09:26:49 volumio nmbd[965]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.34 for name WORKGROUP<1d>. Mar 13 09:26:49 volumio nmbd[965]: This response was from IP 192.168.3.69, reporting an IP address of 192.168.3.69. Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::ClearQueue Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:50 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::serviceStop Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 11 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::serviceStop Mar 13 09:26:50 volumio volumio[23246]: info: ControllerMpd::stop Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::clearPlayQueue Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::saveQueue Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushQueue Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::addQueueItems Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::addQueueItems Mar 13 09:26:50 volumio volumio[23246]: info: Adding Item to queue: music-library/NAS/sunservice/SONY 古典/灵感(Inspiration) Mar 13 09:26:50 volumio volumio[23246]: info: Exploding uri music-library/NAS/sunservice/SONY 古典/灵感(Inspiration) in service mpd Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand stop took 164 milliseconds Mar 13 09:26:50 volumio volumio[23246]: info: Mar 13 09:26:50 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F01.Fly.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/01.Fly.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F02.Close%20Your%20Eyes.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/02.Close Your Eyes.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F03.Moments.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/03.Moments.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F04.Concertino.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/04.Concertino.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F05.Sehnsucht.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/05.Sehnsucht.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F06.C'est%20la%20vie.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/06.C'est la vie.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F07.Focus.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/07.Focus.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F08.Das%20Streben%20nach%20Gl%C3%BCck.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/08.Das Streben nach Glück.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F09.Der%20gr%C3%BCne%20Ballon.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/09.Der grüne Ballon.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F10.Geborgenheit.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/10.Geborgenheit.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F11.November.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/11.November.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F12.Gl%C3%BCcksmoment.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/12.Glücksmoment.flac Mar 13 09:26:50 volumio volumio[23246]: info: ALBUMART /albumart?cacheid=254&web=Florian%20Christl%2CThe%20Modern%20String%20Quintet/Inspiration/extralarge&path=%2Fmnt%2FNAS%2Fsunservice%2FSONY%20%E5%8F%A4%E5%85%B8%2F%E7%81%B5%E6%84%9F(Inspiration)%2F13.Inspiration.flac&metadata=false Mar 13 09:26:50 volumio volumio[23246]: info: URI /mnt/NAS/sunservice/SONY 古典/灵感(Inspiration)/13.Inspiration.flac Mar 13 09:26:50 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::saveQueue Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushQueue Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::updateTrackBlock Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrackBlock Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPlay Mar 13 09:26:50 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::play index 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::stop Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:50 volumio volumio[23246]: verbose: UNSET VOLATILE Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::play index undefined Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::startPlaybackTimer Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::clearAddPlayTracks NAS/sunservice/SONY 古典/灵感(Inspiration)/01.Fly.flac Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand status took 21 milliseconds Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand stop took 5 milliseconds Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 09:26:50 volumio volumio[23246]: info: Mar 13 09:26:50 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:50 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand clear took 4 milliseconds Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand add "NAS/sunservice/SONY 古典/灵感(Inspiration)/01.Fly.flac" Mar 13 09:26:50 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Rainbow Connection","artist":"Nicki Parrott","album":"Yesterday Once More","uri":"NAS/sunservice/SONY 古典/Yesterday Once More (2.8MHz DSD)/12.The Rainbow Connection.dsf","trackType":"dsf"} Mar 13 09:26:50 volumio volumio[23246]: verbose: CURRENT POSITION 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::syncState stateService stop Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: No code Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: ------------------------------ 66ms Mar 13 09:26:50 volumio volumio[23246]: error: updateQueue error: null Mar 13 09:26:50 volumio volumio[23246]: info: Mar 13 09:26:50 volumio volumio[23246]: ---------------------------- MPD announces system playlist update Mar 13 09:26:50 volumio volumio[23246]: info: Ignoring MPD Status Update Mar 13 09:26:50 volumio volumio[23246]: info: ------------------------------ 39ms Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand add "NAS/sunservice/SONY 古典/灵感(Inspiration)/01.Fly.flac" took 37 milliseconds Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand play Mar 13 09:26:50 volumio volumio[23246]: info: ------------------------------ 10ms Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand play took 8 milliseconds Mar 13 09:26:50 volumio volumio[23246]: info: Mar 13 09:26:50 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:50 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:50 volumio volumio[23246]: info: Mar 13 09:26:50 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:26:50 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand status took 3 milliseconds Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand status took 3 milliseconds Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:50 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1191 Kbps","isStreaming":false,"title":"Fly","artist":"Florian Christl","album":"Inspiration","uri":"NAS/sunservice/SONY 古典/灵感(Inspiration)/01.Fly.flac","trackType":"flac"} Mar 13 09:26:50 volumio volumio[23246]: verbose: CURRENT POSITION 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus stop Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: ------------------------------ 18ms Mar 13 09:26:50 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 13 09:26:50 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:26:50 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1191 Kbps","isStreaming":false,"title":"Fly","artist":"Florian Christl","album":"Inspiration","uri":"NAS/sunservice/SONY 古典/灵感(Inspiration)/01.Fly.flac","trackType":"flac"} Mar 13 09:26:50 volumio volumio[23246]: verbose: CURRENT POSITION 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::syncState stateService play Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus play Mar 13 09:26:50 volumio volumio[23246]: info: Received an update from plugin. extracting info from payload Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:26:50 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:26:50 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:26:50 volumio volumio[23246]: info: ------------------------------ 34ms Mar 13 09:27:11 volumio volumio[23246]: info: CoreCommandRouter::volumioPause Mar 13 09:27:11 volumio volumio[23246]: info: CoreStateMachine::pause Mar 13 09:27:11 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:27:11 volumio volumio[23246]: info: CoreStateMachine::servicePause Mar 13 09:27:11 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:27:11 volumio volumio[23246]: info: CoreCommandRouter::servicePause Mar 13 09:27:11 volumio volumio[23246]: info: ControllerMpd::pause Mar 13 09:27:11 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand pause Mar 13 09:27:11 volumio volumio[23246]: info: sendMpdCommand pause took 84 milliseconds Mar 13 09:27:11 volumio volumio[23246]: info: Mar 13 09:27:11 volumio volumio[23246]: ---------------------------- MPD announces state update: player Mar 13 09:27:11 volumio volumio[23246]: info: ControllerMpd::getState Mar 13 09:27:11 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand status Mar 13 09:27:11 volumio volumio[23246]: info: sendMpdCommand status took 1 milliseconds Mar 13 09:27:11 volumio volumio[23246]: verbose: ControllerMpd::parseState Mar 13 09:27:11 volumio volumio[23246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 09:27:11 volumio volumio[23246]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 13 09:27:11 volumio volumio[23246]: verbose: ControllerMpd::parseTrackInfo Mar 13 09:27:11 volumio volumio[23246]: info: ControllerMpd::pushState Mar 13 09:27:11 volumio volumio[23246]: info: CoreCommandRouter::servicePushState Mar 13 09:27:11 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:27:11 volumio volumio[23246]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21234,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1141 Kbps","isStreaming":false,"title":"Fly","artist":"Florian Christl","album":"Inspiration","uri":"NAS/sunservice/SONY 古典/灵感(Inspiration)/01.Fly.flac","trackType":"flac"} Mar 13 09:27:11 volumio volumio[23246]: verbose: CURRENT POSITION 0 Mar 13 09:27:11 volumio volumio[23246]: info: CoreStateMachine::syncState stateService pause Mar 13 09:27:11 volumio volumio[23246]: info: CoreStateMachine::syncState currentStatus pause Mar 13 09:27:11 volumio volumio[23246]: info: CoreStateMachine::pushState Mar 13 09:27:11 volumio volumio[23246]: info: CorePlayQueue::getTrack 0 Mar 13 09:27:11 volumio volumio[23246]: info: CoreCommandRouter::volumioPushState Mar 13 09:27:11 volumio volumio[23246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 09:27:11 volumio volumio[23246]: info: CoreStateMachine::stPlaybackTimer Mar 13 09:27:11 volumio volumio[23246]: info: ------------------------------ 15ms Mar 13 09:27:24 volumio volumio[23246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 09:27:24 volumio volumio[23246]: { Error: connect ETIMEDOUT 31.13.94.10:443 Mar 13 09:27:24 volumio volumio[23246]: at Object._errnoException (util.js:1022:11) Mar 13 09:27:24 volumio volumio[23246]: at _exceptionWithHostPort (util.js:1044:20) Mar 13 09:27:24 volumio volumio[23246]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Mar 13 09:27:24 volumio volumio[23246]: code: 'ETIMEDOUT', Mar 13 09:27:24 volumio volumio[23246]: errno: 'ETIMEDOUT', Mar 13 09:27:24 volumio volumio[23246]: syscall: 'connect', Mar 13 09:27:24 volumio volumio[23246]: address: '31.13.94.10', Mar 13 09:27:24 volumio volumio[23246]: port: 443 } Mar 13 09:27:24 volumio volumio[23246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 09:27:24 volumio sudo[11684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 09:26 Mar 13 09:27:24 volumio sudo[11684]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"