Dec 13 22:34:05 volumio volumio[6201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 22:34:05 volumio volumio[6201]: info: CURURI: albums://Sunset%20Rollercoaster/QUIT%20QUIETLY Dec 13 22:34:05 volumio volumio[6201]: info: Preload queue cleared Dec 13 22:34:05 volumio volumio[6201]: info: No valid Plugin REST Endpoint Dec 13 22:34:05 volumio volumio[6201]: info: No valid Plugin REST Endpoint Dec 13 22:34:09 volumio volumio[6201]: info: Preload queue cleared Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::ClearQueue Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::stPlaybackTimer Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::updateTrackBlock Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrackBlock Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 1 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::serviceStop Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 1 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::serviceStop Dec 13 22:34:09 volumio volumio[6201]: info: ControllerMpd::stop Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::clearPlayQueue Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::saveQueue Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushQueue Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::addQueueItems Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::addQueueItems Dec 13 22:34:09 volumio volumio[6201]: info: Preload queue cleared Dec 13 22:34:09 volumio volumio[6201]: info: Adding Item to queue: albums://Sunset%20Rollercoaster/QUIT%20QUIETLY Dec 13 22:34:09 volumio volumio[6201]: info: Exploding uri albums://Sunset%20Rollercoaster/QUIT%20QUIETLY in service mpd Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand stop took 10 milliseconds Dec 13 22:34:09 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushQueue Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::saveQueue Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::updateTrackBlock Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrackBlock Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPlay Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::play index 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::play index undefined Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::startPlaybackTimer Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::clearAddPlayTracks NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand status took 5 milliseconds Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand stop took 2 milliseconds Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:09 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:09 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces system playlist update Dec 13 22:34:09 volumio volumio[6201]: info: Ignoring MPD Status Update Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand clear took 1 milliseconds Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand status took 2 milliseconds Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand status took 1 milliseconds Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::parseTrackInfo Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand add "NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac" Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:09 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: 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":"Greedy","artist":"Sunset Rollercoaster","album":"CASSA NOVA","uri":"NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - CASSA NOVA/2. Sunset Rollercoaster - Greedy.flac","trackType":"flac"} Dec 13 22:34:09 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::syncState stateService stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: No code Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: 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} Dec 13 22:34:09 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::syncState stateService stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: No code Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: 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} Dec 13 22:34:09 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::syncState stateService stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus stop Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: No code Dec 13 22:34:09 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:09 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:09 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 39ms Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 32ms Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 32ms Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces system playlist update Dec 13 22:34:09 volumio volumio[6201]: info: Ignoring MPD Status Update Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces system playlist update Dec 13 22:34:09 volumio volumio[6201]: info: Ignoring MPD Status Update Dec 13 22:34:09 volumio volumio[6201]: error: updateQueue error: null Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces system playlist update Dec 13 22:34:09 volumio volumio[6201]: info: Ignoring MPD Status Update Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 33ms Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand add "NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac" took 33 milliseconds Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 2ms Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 2ms Dec 13 22:34:09 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand play Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces system playlist update Dec 13 22:34:09 volumio volumio[6201]: info: Ignoring MPD Status Update Dec 13 22:34:09 volumio volumio[6201]: info: Dec 13 22:34:09 volumio volumio[6201]: ---------------------------- MPD announces system playlist update Dec 13 22:34:09 volumio volumio[6201]: info: Ignoring MPD Status Update Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 14ms Dec 13 22:34:09 volumio volumio[6201]: info: sendMpdCommand play took 13 milliseconds Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 13ms Dec 13 22:34:09 volumio volumio[6201]: info: ------------------------------ 13ms Dec 13 22:34:10 volumio volumio[6201]: info: Dec 13 22:34:10 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:10 volumio volumio[6201]: info: Dec 13 22:34:10 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:10 volumio volumio[6201]: info: Dec 13 22:34:10 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:10 volumio volumio[6201]: info: Dec 13 22:34:10 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand status took 2 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 22:34:10 volumio volumio[6201]: info: Dec 13 22:34:10 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:10 volumio volumio[6201]: info: Dec 13 22:34:10 volumio volumio[6201]: ---------------------------- MPD announces state update: player Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::getState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand status Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand status took 5 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand status took 4 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand status took 4 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand status took 3 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand status took 3 milliseconds Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseTrackInfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseState Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1116,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2080 Kbps","isStreaming":false,"title":"Wind of Tomorrow","artist":"Sunset Rollercoaster","album":"QUIT QUIETLY","uri":"NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac","trackType":"flac"} Dec 13 22:34:10 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState stateService play Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus stop Dec 13 22:34:10 volumio volumio[6201]: info: ------------------------------ 8ms Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 13 22:34:10 volumio volumio[6201]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseTrackInfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseTrackInfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseTrackInfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseTrackInfo Dec 13 22:34:10 volumio volumio[6201]: verbose: ControllerMpd::parseTrackInfo Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1116,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2186 Kbps","isStreaming":false,"title":"Wind of Tomorrow","artist":"Sunset Rollercoaster","album":"QUIT QUIETLY","uri":"NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac","trackType":"flac"} Dec 13 22:34:10 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState stateService play Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus play Dec 13 22:34:10 volumio volumio[6201]: info: Received an update from plugin. extracting info from payload Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1116,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2186 Kbps","isStreaming":false,"title":"Wind of Tomorrow","artist":"Sunset Rollercoaster","album":"QUIT QUIETLY","uri":"NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac","trackType":"flac"} Dec 13 22:34:10 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState stateService play Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus play Dec 13 22:34:10 volumio volumio[6201]: info: Received an update from plugin. extracting info from payload Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1116,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2186 Kbps","isStreaming":false,"title":"Wind of Tomorrow","artist":"Sunset Rollercoaster","album":"QUIT QUIETLY","uri":"NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac","trackType":"flac"} Dec 13 22:34:10 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState stateService play Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus play Dec 13 22:34:10 volumio volumio[6201]: info: Received an update from plugin. extracting info from payload Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1116,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2186 Kbps","isStreaming":false,"title":"Wind of Tomorrow","artist":"Sunset Rollercoaster","album":"QUIT QUIETLY","uri":"NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac","trackType":"flac"} Dec 13 22:34:10 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState stateService play Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus play Dec 13 22:34:10 volumio volumio[6201]: info: Received an update from plugin. extracting info from payload Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: ControllerMpd::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::servicePushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1116,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2186 Kbps","isStreaming":false,"title":"Wind of Tomorrow","artist":"Sunset Rollercoaster","album":"QUIT QUIETLY","uri":"NAS/TS-464C2/HIRES/TIDAL MAX/Sunset Rollercoaster - QUIT QUIETLY/01. Sunset Rollercoaster - Wind of Tomorrow.flac","trackType":"flac"} Dec 13 22:34:10 volumio volumio[6201]: verbose: CURRENT POSITION 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState stateService play Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::syncState currentStatus play Dec 13 22:34:10 volumio volumio[6201]: info: Received an update from plugin. extracting info from payload Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: CoreStateMachine::pushState Dec 13 22:34:10 volumio volumio[6201]: info: CorePlayQueue::getTrack 0 Dec 13 22:34:10 volumio volumio[6201]: info: CoreCommandRouter::volumioPushState Dec 13 22:34:10 volumio volumio[6201]: info: ------------------------------ 54ms Dec 13 22:34:10 volumio volumio[6201]: info: ------------------------------ 53ms Dec 13 22:34:10 volumio volumio[6201]: info: ------------------------------ 54ms Dec 13 22:34:10 volumio volumio[6201]: info: ------------------------------ 52ms Dec 13 22:34:10 volumio volumio[6201]: info: ------------------------------ 52ms Dec 13 22:34:22 volumio volumio[6201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 22:34:22 volumio volumio[6201]: info: CURURI: albums:// Dec 13 22:34:22 volumio volumio[6201]: info: listAlbums - loading Albums from cache Dec 13 22:34:22 volumio volumio[6201]: info: Preload queue cleared Dec 13 22:34:53 volumio volumio[6201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 13 22:34:53 volumio volumio[6201]: info: CURURI: albums://HYUKOH%2C%20Sunset%20Rollercoaster/AAA Dec 13 22:34:53 volumio volumio[6201]: info: Preload queue cleared Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/1. HYUKOH, Sunset Rollercoaster - Kite War.flac Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/2. HYUKOH, Sunset Rollercoaster - Y.flac Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/3. HYUKOH, Sunset Rollercoaster - Antenna.flac Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/4. HYUKOH, Sunset Rollercoaster - Glue.flac Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/5. HYUKOH, Sunset Rollercoaster - Young Man.flac Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/6. HYUKOH, Sunset Rollercoaster - Do Nothing.flac Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/7. HYUKOH, Sunset Rollercoaster - Aaaannnnteeeeennnaaaaaa.flac Dec 13 22:34:53 volumio volumio[6201]: info: Preloading song: music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/8. HYUKOH, Sunset Rollercoaster - 2F 年轻人.flac Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/1. HYUKOH, Sunset Rollercoaster - Kite War.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F1.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%20Kite%20War.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/1. HYUKOH, Sunset Rollercoaster - Kite War.flac Dec 13 22:34:53 volumio volumio[6201]: info: No valid Plugin REST Endpoint Dec 13 22:34:53 volumio volumio[6201]: info: No valid Plugin REST Endpoint Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/2. HYUKOH, Sunset Rollercoaster - Y.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F2.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%20Y.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/2. HYUKOH, Sunset Rollercoaster - Y.flac Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/3. HYUKOH, Sunset Rollercoaster - Antenna.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F3.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%20Antenna.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/3. HYUKOH, Sunset Rollercoaster - Antenna.flac Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/4. HYUKOH, Sunset Rollercoaster - Glue.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F4.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%20Glue.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/4. HYUKOH, Sunset Rollercoaster - Glue.flac Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/5. HYUKOH, Sunset Rollercoaster - Young Man.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F5.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%20Young%20Man.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/5. HYUKOH, Sunset Rollercoaster - Young Man.flac Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/6. HYUKOH, Sunset Rollercoaster - Do Nothing.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F6.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%20Do%20Nothing.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/6. HYUKOH, Sunset Rollercoaster - Do Nothing.flac Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/7. HYUKOH, Sunset Rollercoaster - Aaaannnnteeeeennnaaaaaa.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F7.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%20Aaaannnnteeeeennnaaaaaa.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/7. HYUKOH, Sunset Rollercoaster - Aaaannnnteeeeennnaaaaaa.flac Dec 13 22:34:53 volumio volumio[6201]: info: Exploding uri music-library/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/8. HYUKOH, Sunset Rollercoaster - 2F 年轻人.flac in service mpd Dec 13 22:34:53 volumio volumio[6201]: info: ALBUMART /albumart?cacheid=570&path=%2Fmnt%2FNAS%2FTS-464C2%2FHIRES%2FTIDAL%20MAX%2FHYUKOH%20-%20AAA%2F8.%20HYUKOH%2C%20Sunset%20Rollercoaster%20-%202F%20%E5%B9%B4%E8%BD%BB%E4%BA%BA.flac&metadata=false Dec 13 22:34:53 volumio volumio[6201]: info: URI /mnt/NAS/TS-464C2/HIRES/TIDAL MAX/HYUKOH - AAA/8. HYUKOH, Sunset Rollercoaster - 2F 年轻人.flac Dec 13 22:35:07 volumio volumio[6201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 22:35:07 volumio volumio[6201]: Error: connect ETIMEDOUT 69.63.184.30:443 Dec 13 22:35:07 volumio volumio[6201]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 13 22:35:07 volumio volumio[6201]: errno: -110, Dec 13 22:35:07 volumio volumio[6201]: code: 'ETIMEDOUT', Dec 13 22:35:07 volumio volumio[6201]: syscall: 'connect', Dec 13 22:35:07 volumio volumio[6201]: address: '69.63.184.30', Dec 13 22:35:07 volumio volumio[6201]: port: 443 Dec 13 22:35:07 volumio volumio[6201]: } Dec 13 22:35:07 volumio volumio[6201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 22:35:07 volumio sudo[11769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 22:34' Dec 13 22:35:07 volumio sudo[11769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"