-- Logs begin at Mon 2025-09-15 18:16:09 CST, end at Mon 2025-09-15 18:46:42 CST. -- Sep 15 18:45:00 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 18:45:00 volumio volumio[1105]: info: CURURI: artists://%E9%BB%91%E9%B8%AD%E5%AD%90 Sep 15 18:45:00 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:00 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:02 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::ClearQueue Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::serviceStop Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::serviceStop Sep 15 18:45:02 volumio volumio[1105]: info: ControllerMpd::stop Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::clearPlayQueue Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::saveQueue Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushQueue Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::addQueueItems Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::addQueueItems Sep 15 18:45:02 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:02 volumio volumio[1105]: info: Adding Item to queue: artists://%E9%BB%91%E9%B8%AD%E5%AD%90 Sep 15 18:45:02 volumio volumio[1105]: info: Exploding uri artists://%E9%BB%91%E9%B8%AD%E5%AD%90 in service mpd Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand stop took 5 milliseconds Sep 15 18:45:02 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushQueue Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::saveQueue Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPlay Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::play index 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::play index undefined Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::startPlaybackTimer Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/让我们荡起双桨.wav Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand status took 4 milliseconds Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand stop took 0 milliseconds Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:02 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:02 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:02 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand clear took 1 milliseconds Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/让我们荡起双桨.wav" Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:02 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: 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":"零时十分","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/01 - 叶倩文 - 零时十分.dsf","trackType":"dsf"} Sep 15 18:45:02 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::syncState stateService stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: No code Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 18:45:02 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::syncState stateService stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: No code Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: ------------------------------ 24ms Sep 15 18:45:02 volumio volumio[1105]: info: ------------------------------ 19ms Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:02 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:02 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:02 volumio volumio[1105]: error: updateQueue error: null Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:02 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand status took 20 milliseconds Sep 15 18:45:02 volumio volumio[1105]: info: ------------------------------ 20ms Sep 15 18:45:02 volumio volumio[1105]: info: sendMpdCommand add "INTERNAL/让我们荡起双桨.wav" took 20 milliseconds Sep 15 18:45:02 volumio volumio[1105]: info: ------------------------------ 2ms Sep 15 18:45:02 volumio volumio[1105]: info: ------------------------------ 1ms Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:02 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand play Sep 15 18:45:02 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 18:45:02 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::syncState stateService stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: No code Sep 15 18:45:02 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:02 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:02 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:02 volumio volumio[1105]: info: ------------------------------ 30ms Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:02 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:02 volumio volumio[1105]: info: Sep 15 18:45:02 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:02 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:03 volumio volumio[1105]: info: Sep 15 18:45:03 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 16ms Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand play took 16 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 7ms Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 6ms Sep 15 18:45:03 volumio volumio[1105]: info: Sep 15 18:45:03 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:03 volumio volumio[1105]: info: Sep 15 18:45:03 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:03 volumio volumio[1105]: info: Sep 15 18:45:03 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand status took 50 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand status took 48 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand status took 47 milliseconds Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:03 volumio volumio[1105]: info: Sep 15 18:45:03 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:03 volumio volumio[1105]: info: Sep 15 18:45:03 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand status took 3 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand status took 2 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand status took 2 milliseconds Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"让我们荡起双桨","artist":"黑鸭子","album":"惠威试音天碟Ⅳ-惠威综合试音3","uri":"INTERNAL/让我们荡起双桨.wav","trackType":"wav"} Sep 15 18:45:03 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"让我们荡起双桨","artist":"黑鸭子","album":"惠威试音天碟Ⅳ-惠威综合试音3","uri":"INTERNAL/让我们荡起双桨.wav","trackType":"wav"} Sep 15 18:45:03 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:03 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"让我们荡起双桨","artist":"黑鸭子","album":"惠威试音天碟Ⅳ-惠威综合试音3","uri":"INTERNAL/让我们荡起双桨.wav","trackType":"wav"} Sep 15 18:45:03 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:03 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 63ms Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 66ms Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 66ms Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 15 18:45:03 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:03 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"让我们荡起双桨","artist":"黑鸭子","album":"惠威试音天碟Ⅳ-惠威综合试音3","uri":"INTERNAL/让我们荡起双桨.wav","trackType":"wav"} Sep 15 18:45:03 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:03 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"让我们荡起双桨","artist":"黑鸭子","album":"惠威试音天碟Ⅳ-惠威综合试音3","uri":"INTERNAL/让我们荡起双桨.wav","trackType":"wav"} Sep 15 18:45:03 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:03 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"让我们荡起双桨","artist":"黑鸭子","album":"惠威试音天碟Ⅳ-惠威综合试音3","uri":"INTERNAL/让我们荡起双桨.wav","trackType":"wav"} Sep 15 18:45:03 volumio volumio[1105]: verbose: CURRENT POSITION 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:03 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:03 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:03 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 40ms Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 38ms Sep 15 18:45:03 volumio volumio[1105]: info: ------------------------------ 38ms Sep 15 18:45:37 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 18:45:37 volumio volumio[1105]: info: CURURI: artists://%E5%8F%B6%E5%80%A9%E6%96%87 Sep 15 18:45:37 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:37 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:37 volumio volumio[1105]: info: Preloading song: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/10 - 叶倩文 - 可能.dsf Sep 15 18:45:37 volumio volumio[1105]: info: Preloading song: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/11 - 叶倩文 - Never Gonna Let You Go.dsf Sep 15 18:45:37 volumio volumio[1105]: info: Exploding uri music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/10 - 叶倩文 - 可能.dsf in service mpd Sep 15 18:45:37 volumio volumio[1105]: info: Exploding uri music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/11 - 叶倩文 - Never Gonna Let You Go.dsf in service mpd Sep 15 18:45:37 volumio volumio[1105]: info: ALBUMART /albumart?cacheid=759&web=%E5%8F%B6%E5%80%A9%E6%96%87/Sally%20Yeh%20%E9%9B%B6%E6%97%B6%E5%8D%81%E5%88%86/extralarge&path=%2Fmnt%2FNAS%2FWebMusic%2F%E5%8F%B6%E5%80%A9%E6%96%87%20-%20%E9%9B%B6%E6%97%B6%E5%8D%81%E5%88%86%20-%202015%20%20%20DSD%20DSF%2F10%20-%20%E5%8F%B6%E5%80%A9%E6%96%87%20-%20%E5%8F%AF%E8%83%BD.dsf&metadata=false Sep 15 18:45:37 volumio volumio[1105]: info: URI /mnt/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/10 - 叶倩文 - 可能.dsf Sep 15 18:45:37 volumio volumio[1105]: info: ALBUMART /albumart?cacheid=759&web=%E5%8F%B6%E5%80%A9%E6%96%87/Sally%20Yeh%20%E9%9B%B6%E6%97%B6%E5%8D%81%E5%88%86/extralarge&path=%2Fmnt%2FNAS%2FWebMusic%2F%E5%8F%B6%E5%80%A9%E6%96%87%20-%20%E9%9B%B6%E6%97%B6%E5%8D%81%E5%88%86%20-%202015%20%20%20DSD%20DSF%2F11%20-%20%E5%8F%B6%E5%80%A9%E6%96%87%20-%20Never%20Gonna%20Let%20You%20Go.dsf&metadata=false Sep 15 18:45:37 volumio volumio[1105]: info: URI /mnt/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/11 - 叶倩文 - Never Gonna Let You Go.dsf Sep 15 18:45:37 volumio volumio[1105]: An internal error occurred while serving an albumart. Details: Error: ENOSPC: no space left on device, mkdir '/data/albumart/folder/mnt/NAS' Sep 15 18:45:37 volumio volumio[1105]: at Object.mkdirSync (fs.js:1014:3) Sep 15 18:45:37 volumio volumio[1105]: at mkdirsSync (/volumio/node_modules/fs-extra/lib/mkdirs/mkdirs-sync.js:31:9) Sep 15 18:45:37 volumio volumio[1105]: at mkdirsSync (/volumio/node_modules/fs-extra/lib/mkdirs/mkdirs-sync.js:37:16) Sep 15 18:45:37 volumio volumio[1105]: at Object.mkdirsSync (/volumio/node_modules/fs-extra/lib/mkdirs/mkdirs-sync.js:37:16) Sep 15 18:45:37 volumio volumio[1105]: at Object.createFileSync (/volumio/node_modules/fs-extra/lib/ensure/file.js:37:11) Sep 15 18:45:37 volumio volumio[1105]: at searchInFolder (/volumio/app/plugins/miscellanea/albumart/albumart.js:239:16) Sep 15 18:45:37 volumio volumio[1105]: at processRequest (/volumio/app/plugins/miscellanea/albumart/albumart.js:386:11) Sep 15 18:45:37 volumio volumio[1105]: at processExpressRequest (/volumio/app/plugins/miscellanea/albumart/albumart.js:437:17) Sep 15 18:45:37 volumio volumio[1105]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 15 18:45:37 volumio volumio[1105]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 15 18:45:37 volumio volumio[1105]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 15 18:45:37 volumio volumio[1105]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 15 18:45:37 volumio volumio[1105]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 15 18:45:37 volumio volumio[1105]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 15 18:45:37 volumio volumio[1105]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 15 18:45:37 volumio volumio[1105]: at allowCrossDomain (/volumio/http/index.js:34:5) Sep 15 18:45:47 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::ClearQueue Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::stop Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::serviceStop Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::serviceStop Sep 15 18:45:47 volumio volumio[1105]: info: ControllerMpd::stop Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::clearPlayQueue Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::saveQueue Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushQueue Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::addQueueItems Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::addQueueItems Sep 15 18:45:47 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/01 - 叶倩文 - 零时十分.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/01 - 叶倩文 - 零时十分.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/02 - 叶倩文 - 疯女.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/02 - 叶倩文 - 疯女.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/03 - 叶倩文 - 爱得太尽.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/03 - 叶倩文 - 爱得太尽.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushQueue Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::saveQueue Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPlay Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::play index 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::addQueueItems Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::addQueueItems Sep 15 18:45:47 volumio volumio[1105]: info: Preload queue cleared Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/05 - 叶倩文 - 情话绵绵.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/05 - 叶倩文 - 情话绵绵.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/06 - 叶倩文 - She Works Hard For The Money.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/06 - 叶倩文 - She Works Hard For The Money.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/07 - 叶倩文 - 星与云.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/07 - 叶倩文 - 星与云.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/08 - 叶倩文 - 新双星情歌.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/08 - 叶倩文 - 新双星情歌.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/09 - 叶倩文 - 千金难得美人心.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/09 - 叶倩文 - 千金难得美人心.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/10 - 叶倩文 - 可能.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/10 - 叶倩文 - 可能.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Adding Item to queue: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/11 - 叶倩文 - Never Gonna Let You Go.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Using cached record of: music-library/NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/11 - 叶倩文 - Never Gonna Let You Go.dsf Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::stop Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushQueue Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::saveQueue Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::play index undefined Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::startPlaybackTimer Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::clearAddPlayTracks NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf Sep 15 18:45:47 volumio volumio[1105]: info: Setting Volume to 100 automatically for DSD Sep 15 18:45:47 volumio volumio[1105]: info: VolumeController::SetAlsaVolume100 Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand stop took 27 milliseconds Sep 15 18:45:47 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand stop took 7 milliseconds Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:47 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:47 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:47 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand status took 2 milliseconds Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand clear took 2 milliseconds Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand add "NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf" Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:47 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 18:45:47 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::syncState stateService stop Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: No code Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 18:45:47 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::syncState stateService stop Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: No code Sep 15 18:45:47 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:47 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:47 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 19ms Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 19ms Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:47 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:47 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:47 volumio volumio[1105]: error: updateQueue error: null Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:47 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 21ms Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand add "NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf" took 20 milliseconds Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 2ms Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 2ms Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:47 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand play Sep 15 18:45:47 volumio volumio[1105]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 24ms Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:47 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:47 volumio volumio[1105]: info: Sep 15 18:45:47 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Sep 15 18:45:47 volumio volumio[1105]: info: Ignoring MPD Status Update Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 5ms Sep 15 18:45:47 volumio volumio[1105]: info: sendMpdCommand play took 4 milliseconds Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 4ms Sep 15 18:45:47 volumio volumio[1105]: info: ------------------------------ 3ms Sep 15 18:45:47 volumio volumio[1105]: An internal error occurred while serving an albumart. Details: Error: ENOSPC: no space left on device, mkdir '/data/albumart/folder/mnt/NAS' Sep 15 18:45:47 volumio volumio[1105]: at Object.mkdirSync (fs.js:1014:3) Sep 15 18:45:47 volumio volumio[1105]: at mkdirsSync (/volumio/node_modules/fs-extra/lib/mkdirs/mkdirs-sync.js:31:9) Sep 15 18:45:47 volumio volumio[1105]: at mkdirsSync (/volumio/node_modules/fs-extra/lib/mkdirs/mkdirs-sync.js:37:16) Sep 15 18:45:47 volumio volumio[1105]: at Object.mkdirsSync (/volumio/node_modules/fs-extra/lib/mkdirs/mkdirs-sync.js:37:16) Sep 15 18:45:47 volumio volumio[1105]: at Object.createFileSync (/volumio/node_modules/fs-extra/lib/ensure/file.js:37:11) Sep 15 18:45:47 volumio volumio[1105]: at searchInFolder (/volumio/app/plugins/miscellanea/albumart/albumart.js:239:16) Sep 15 18:45:47 volumio volumio[1105]: at processRequest (/volumio/app/plugins/miscellanea/albumart/albumart.js:386:11) Sep 15 18:45:47 volumio volumio[1105]: at processExpressRequest (/volumio/app/plugins/miscellanea/albumart/albumart.js:437:17) Sep 15 18:45:47 volumio volumio[1105]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 15 18:45:47 volumio volumio[1105]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 15 18:45:47 volumio volumio[1105]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 15 18:45:47 volumio volumio[1105]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 15 18:45:47 volumio volumio[1105]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 15 18:45:47 volumio volumio[1105]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 15 18:45:47 volumio volumio[1105]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 15 18:45:47 volumio volumio[1105]: at allowCrossDomain (/volumio/http/index.js:34:5) Sep 15 18:45:48 volumio volumio[1105]: info: Sep 15 18:45:48 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:48 volumio volumio[1105]: info: Sep 15 18:45:48 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:48 volumio volumio[1105]: info: Sep 15 18:45:48 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:48 volumio volumio[1105]: info: Sep 15 18:45:48 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand status took 18 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand status took 18 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand status took 18 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand status took 0 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:45:48 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Sep 15 18:45:48 volumio volumio[1105]: info: ------------------------------ 21ms Sep 15 18:45:48 volumio volumio[1105]: info: Sep 15 18:45:48 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:48 volumio volumio[1105]: info: Sep 15 18:45:48 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand status took 2 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:45:48 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:48 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:45:48 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:48 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:45:48 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:48 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: ------------------------------ 41ms Sep 15 18:45:48 volumio volumio[1105]: info: ------------------------------ 41ms Sep 15 18:45:48 volumio volumio[1105]: info: ------------------------------ 24ms Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 15 18:45:48 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:48 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:45:48 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:48 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:45:48 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Sep 15 18:45:48 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:45:48 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:45:48 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:45:48 volumio volumio[1105]: info: ------------------------------ 33ms Sep 15 18:45:48 volumio volumio[1105]: info: ------------------------------ 34ms Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::volumioPause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::pause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::servicePause Sep 15 18:46:34 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::servicePause Sep 15 18:46:34 volumio volumio[1105]: info: ControllerMpd::pause Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand pause Sep 15 18:46:34 volumio volumio[1105]: info: sendMpdCommand pause took 4 milliseconds Sep 15 18:46:34 volumio volumio[1105]: info: Sep 15 18:46:34 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:46:34 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:46:34 volumio volumio[1105]: info: Sep 15 18:46:34 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:46:34 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:46:34 volumio volumio[1105]: info: Sep 15 18:46:34 volumio volumio[1105]: ---------------------------- MPD announces state update: player Sep 15 18:46:34 volumio volumio[1105]: info: ControllerMpd::getState Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Sep 15 18:46:34 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Sep 15 18:46:34 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:46:34 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Sep 15 18:46:34 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 15 18:46:34 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::parseState Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:46:34 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:46:34 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:46:34 volumio volumio[1105]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":47115,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:46:34 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::syncState stateService pause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus pause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:46:34 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Sep 15 18:46:34 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:46:34 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:46:34 volumio volumio[1105]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":47115,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:46:34 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::syncState stateService pause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus pause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:46:34 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Sep 15 18:46:34 volumio volumio[1105]: info: ------------------------------ 11ms Sep 15 18:46:34 volumio volumio[1105]: info: ------------------------------ 10ms Sep 15 18:46:34 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 15 18:46:34 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Sep 15 18:46:34 volumio volumio[1105]: info: ControllerMpd::pushState Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Sep 15 18:46:34 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:46:34 volumio volumio[1105]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":47115,"duration":214,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"将来那天","artist":"叶倩文","album":"Sally Yeh 零时十分","uri":"NAS/WebMusic/叶倩文 - 零时十分 - 2015 DSD DSF/04 - 叶倩文 - 将来那天.dsf","trackType":"dsf"} Sep 15 18:46:34 volumio volumio[1105]: verbose: CURRENT POSITION 3 Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::syncState stateService pause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus pause Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::pushState Sep 15 18:46:34 volumio volumio[1105]: info: CorePlayQueue::getTrack 3 Sep 15 18:46:34 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Sep 15 18:46:34 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Sep 15 18:46:34 volumio volumio[1105]: info: ------------------------------ 14ms Sep 15 18:46:42 volumio volumio[1105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:46:42 volumio volumio[1105]: Error: connect ETIMEDOUT 103.230.123.190:80 Sep 15 18:46:42 volumio volumio[1105]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 15 18:46:42 volumio volumio[1105]: errno: -110, Sep 15 18:46:42 volumio volumio[1105]: code: 'ETIMEDOUT', Sep 15 18:46:42 volumio volumio[1105]: syscall: 'connect', Sep 15 18:46:42 volumio volumio[1105]: address: '103.230.123.190', Sep 15 18:46:42 volumio volumio[1105]: port: 80 Sep 15 18:46:42 volumio volumio[1105]: } Sep 15 18:46:42 volumio volumio[1105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:46:42 volumio sudo[6133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:45 Sep 15 18:46:42 volumio sudo[6133]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"