-- Logs begin at Wed 2025-09-10 22:26:47 CST, end at Wed 2025-09-10 22:29:42 CST. -- Sep 10 22:28:00 volumio ntpd[690]: Soliciting pool server 108.59.2.24 Sep 10 22:28:00 volumio ntpd[690]: Soliciting pool server 84.16.73.33 Sep 10 22:28:00 volumio ntpd[690]: Soliciting pool server 193.182.111.14 Sep 10 22:28:00 volumio ntpd[690]: Soliciting pool server 193.182.111.12 Sep 10 22:28:00 volumio volumio[915]: info: Listing playlists Sep 10 22:28:01 volumio ntpd[690]: Soliciting pool server 139.199.215.251 Sep 10 22:28:01 volumio ntpd[690]: Soliciting pool server 202.112.29.82 Sep 10 22:28:02 volumio ntpd[690]: Soliciting pool server 185.209.85.222 Sep 10 22:28:03 volumio ntpd[690]: Soliciting pool server 119.28.206.193 Sep 10 22:28:04 volumio ntpd[690]: Soliciting pool server 2408:4006:1230:6400:91ea:3de5:97b6:d94e Sep 10 22:28:06 volumio volumio[915]: info: Preload queue cleared Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::ClearQueue Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::stop Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::stPlaybackTimer Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::updateTrackBlock Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrackBlock Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::serviceStop Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::serviceStop Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::stop Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::clearPlayQueue Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::saveQueue Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushQueue Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::addQueueItems Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::addQueueItems Sep 10 22:28:06 volumio volumio[915]: info: Preload queue cleared Sep 10 22:28:06 volumio volumio[915]: info: Adding Item to queue: music-library/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96 Sep 10 22:28:06 volumio volumio[915]: info: Exploding uri music-library/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96 in service mpd Sep 10 22:28:06 volumio volumio[915]: info: ALBUMART /albumart?cacheid=943&web=%E4%B8%A5%E8%89%BA%E4%B8%B9/%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA/extralarge&path=%2Fmnt%2FNAS%2Fvolumio%2FSONY%20%E5%8D%8E%E8%AF%AD%2F%5BSony%5D%20-%20%E4%B8%A5%E8%89%BA%E4%B8%B9%20-%20%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA%20-%2024-96%2Fblingbling.flac&metadata=false Sep 10 22:28:06 volumio volumio[915]: info: URI /mnt/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac Sep 10 22:28:06 volumio volumio[915]: info: ALBUMART /albumart?cacheid=943&web=%E4%B8%A5%E8%89%BA%E4%B8%B9/%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA/extralarge&path=%2Fmnt%2FNAS%2Fvolumio%2FSONY%20%E5%8D%8E%E8%AF%AD%2F%5BSony%5D%20-%20%E4%B8%A5%E8%89%BA%E4%B8%B9%20-%20%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA%20-%2024-96%2F%E5%8D%95%E5%BF%83.flac&metadata=false Sep 10 22:28:06 volumio volumio[915]: info: URI /mnt/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/单心.flac Sep 10 22:28:06 volumio volumio[915]: info: ALBUMART /albumart?cacheid=943&web=%E4%B8%A5%E8%89%BA%E4%B8%B9/%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA/extralarge&path=%2Fmnt%2FNAS%2Fvolumio%2FSONY%20%E5%8D%8E%E8%AF%AD%2F%5BSony%5D%20-%20%E4%B8%A5%E8%89%BA%E4%B8%B9%20-%20%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA%20-%2024-96%2F%E5%A6%82%E6%9E%9C%E6%88%91%E7%AD%94%E5%BA%94%E4%BD%A0.flac&metadata=false Sep 10 22:28:06 volumio volumio[915]: info: URI /mnt/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/如果我答应你.flac Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ALBUMART /albumart?cacheid=943&web=%E4%B8%A5%E8%89%BA%E4%B8%B9/%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA/extralarge&path=%2Fmnt%2FNAS%2Fvolumio%2FSONY%20%E5%8D%8E%E8%AF%AD%2F%5BSony%5D%20-%20%E4%B8%A5%E8%89%BA%E4%B8%B9%20-%20%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA%20-%2024-96%2F%E6%88%91%E6%84%BF%E6%84%8F.flac&metadata=false Sep 10 22:28:06 volumio volumio[915]: info: URI /mnt/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/我愿意.flac Sep 10 22:28:06 volumio volumio[915]: info: ALBUMART /albumart?cacheid=943&web=%E4%B8%A5%E8%89%BA%E4%B8%B9/%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA/extralarge&path=%2Fmnt%2FNAS%2Fvolumio%2FSONY%20%E5%8D%8E%E8%AF%AD%2F%5BSony%5D%20-%20%E4%B8%A5%E8%89%BA%E4%B8%B9%20-%20%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA%20-%2024-96%2F%E6%AF%8F%E4%B8%80%E6%AC%A1%E8%AE%A9%E4%BA%BA%E5%BF%83%E7%A2%8E%E7%9A%84.flac&metadata=false Sep 10 22:28:06 volumio volumio[915]: info: URI /mnt/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/每一次让人心碎的.flac Sep 10 22:28:06 volumio volumio[915]: info: ALBUMART /albumart?cacheid=943&web=%E4%B8%A5%E8%89%BA%E4%B8%B9/%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA/extralarge&path=%2Fmnt%2FNAS%2Fvolumio%2FSONY%20%E5%8D%8E%E8%AF%AD%2F%5BSony%5D%20-%20%E4%B8%A5%E8%89%BA%E4%B8%B9%20-%20%E5%85%8B%E6%8B%89%E6%81%8B%E4%BA%BA%20-%2024-96%2F%E6%B2%A1%E9%81%93%E7%90%86.flac&metadata=false Sep 10 22:28:06 volumio volumio[915]: info: URI /mnt/NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/没道理.flac Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand stop took 40 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushQueue Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::saveQueue Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::updateTrackBlock Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrackBlock Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPlay Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::play index 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::stop Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::play index undefined Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::startPlaybackTimer Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::clearAddPlayTracks NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 16 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand stop took 4 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 3 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand clear Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand clear took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand add "NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac" Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: 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":"Airplane Mode","artist":"Dear Jane","album":"Airplane Mode","uri":"NAS/volumio/Mora Qobuz 华语/[Hires]Dear Jane - Hires单曲+EP合集 2013-2020 [24bit48KHz]/Dear Jane - Airplane Mode/1. Airplane Mode.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService stop Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus stop Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: No code Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 22:28:06 volumio volumio[915]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 22:28:06 volumio volumio[915]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 35ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 21ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 21ms Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: error: updateQueue error: null Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 40ms Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand add "NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac" took 38 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 9ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 8ms Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand play Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces system playlist update Sep 10 22:28:06 volumio volumio[915]: info: Ignoring MPD Status Update Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 12ms Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand play took 10 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 10ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 9ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 8ms Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 1 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 1 milliseconds Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: Sep 10 22:28:06 volumio volumio[915]: ---------------------------- MPD announces state update: player Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::getState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 4 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 4 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 3 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand status took 2 milliseconds Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseState Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus stop Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Sep 10 22:28:06 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Sep 10 22:28:06 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Sep 10 22:28:06 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 25ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 73ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 75ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 75ms Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 10 22:28:06 volumio volumio[915]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 10 22:28:06 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Sep 10 22:28:06 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Sep 10 22:28:06 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Sep 10 22:28:06 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: ControllerMpd::pushState Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::servicePushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":0,"seek":847,"duration":210,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2940 Kbps","isStreaming":false,"title":"blingbling","artist":"严艺丹","album":"克拉恋人","uri":"NAS/volumio/SONY 华语/[Sony] - 严艺丹 - 克拉恋人 - 24-96/blingbling.flac","trackType":"flac"} Sep 10 22:28:06 volumio volumio[915]: verbose: CURRENT POSITION 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Sep 10 22:28:06 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: CoreStateMachine::pushState Sep 10 22:28:06 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:28:06 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 148ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 147ms Sep 10 22:28:06 volumio volumio[915]: info: ------------------------------ 146ms Sep 10 22:28:07 volumio ntpd[690]: receive: Unexpected origin timestamp 0xec6c0978.2b904e86 does not match aorg 0000000000.00000000 from server@139.199.215.251 xmt 0xec6c0977.c88fbbd5 Sep 10 22:28:07 volumio ntpd[690]: receive: Unexpected origin timestamp 0xec6c0978.2b8f7628 does not match aorg 0000000000.00000000 from server@202.112.29.82 xmt 0xec6c0977.cbf28094 Sep 10 22:28:07 volumio ntpd[690]: receive: Unexpected origin timestamp 0xec6c0978.2b90dedb does not match aorg 0000000000.00000000 from server@119.28.183.184 xmt 0xec6c0977.cb8a63c9 Sep 10 22:28:07 volumio ntpd[690]: receive: Unexpected origin timestamp 0xec6c0978.2b8a2220 does not match aorg 0000000000.00000000 from server@119.28.206.193 xmt 0xec6c0977.cb80a148 Sep 10 22:28:31 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 40 Sep 10 22:28:31 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 41 Sep 10 22:28:31 volumio volumio[915]: info: CoreCommandRouter::volumioGetState Sep 10 22:28:31 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:29:02 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 Sep 10 22:29:03 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 Sep 10 22:29:03 volumio volumio[915]: info: CoreCommandRouter::volumioGetState Sep 10 22:29:03 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:29:34 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 Sep 10 22:29:34 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 14 Sep 10 22:29:34 volumio volumio[915]: info: CoreCommandRouter::volumioGetState Sep 10 22:29:34 volumio volumio[915]: info: CorePlayQueue::getTrack 0 Sep 10 22:29:42 volumio volumio[915]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 22:29:42 volumio volumio[915]: Error: connect ETIMEDOUT 128.242.240.244:443 Sep 10 22:29:42 volumio volumio[915]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 10 22:29:42 volumio volumio[915]: errno: -110, Sep 10 22:29:42 volumio volumio[915]: code: 'ETIMEDOUT', Sep 10 22:29:42 volumio volumio[915]: syscall: 'connect', Sep 10 22:29:42 volumio volumio[915]: address: '128.242.240.244', Sep 10 22:29:42 volumio volumio[915]: port: 443 Sep 10 22:29:42 volumio volumio[915]: } Sep 10 22:29:42 volumio volumio[915]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 22:29:42 volumio sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 22:28 Sep 10 22:29:42 volumio sudo[2219]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"