-- Logs begin at Sun 2024-12-01 19:00:50 CST, end at Sun 2024-12-01 19:07:50 CST. -- Dec 01 19:06:00 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 19:06:00 volumio volumio[1676]: info: CURURI: albums://%E7%BE%A4%E6%98%9F/%E8%AF%95%E9%9F%B3%E5%AE%9D%E5%85%B8%20%E8%AF%95%E6%9C%BA%E4%BA%8C%E5%8D%81%E5%85%AB%E5%8F%B7 Dec 01 19:06:00 volumio volumio[1676]: info: Preload queue cleared Dec 01 19:06:00 volumio volumio[1676]: info: Preloading song: music-library/NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:00 volumio volumio[1676]: info: Exploding uri music-library/NAS/共享文件/音乐/王闻 - 雨丝情愁.flac in service mpd Dec 01 19:06:00 volumio volumio[1676]: info: ALBUMART /albumart?cacheid=742&web=%E7%BE%A4%E6%98%9F/%E8%AF%95%E9%9F%B3%E5%AE%9D%E5%85%B8%20%E8%AF%95%E6%9C%BA%E4%BA%8C%E5%8D%81%E5%85%AB%E5%8F%B7/extralarge&path=%2Fmnt%2FNAS%2F%E5%85%B1%E4%BA%AB%E6%96%87%E4%BB%B6%2F%E9%9F%B3%E4%B9%90%2F%E7%8E%8B%E9%97%BB%20-%20%E9%9B%A8%E4%B8%9D%E6%83%85%E6%84%81.flac&metadata=false Dec 01 19:06:00 volumio volumio[1676]: info: URI /mnt/NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:00 volumio volumio[1676]: info: No valid Plugin REST Endpoint Dec 01 19:06:00 volumio volumio[1676]: info: No valid Plugin REST Endpoint Dec 01 19:06:02 volumio volumio[1676]: info: Preload queue cleared Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::ClearQueue Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::stop Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::clearPlayQueue Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::saveQueue Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::addQueueItems Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::addQueueItems Dec 01 19:06:02 volumio volumio[1676]: info: Preload queue cleared Dec 01 19:06:02 volumio volumio[1676]: info: Adding Item to queue: albums://%E7%BE%A4%E6%98%9F/%E8%AF%95%E9%9F%B3%E5%AE%9D%E5%85%B8%20%E8%AF%95%E6%9C%BA%E4%BA%8C%E5%8D%81%E5%85%AB%E5%8F%B7 Dec 01 19:06:02 volumio volumio[1676]: info: Exploding uri albums://%E7%BE%A4%E6%98%9F/%E8%AF%95%E9%9F%B3%E5%AE%9D%E5%85%B8%20%E8%AF%95%E6%9C%BA%E4%BA%8C%E5%8D%81%E5%85%AB%E5%8F%B7 in service mpd Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::saveQueue Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::updateTrackBlock Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::getTrackBlock Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::volumioPlay Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::play index 0 Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::stop Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::play index undefined Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::startPlaybackTimer Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::clearAddPlayTracks NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand stop took 3 milliseconds Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 19:06:02 volumio volumio[1676]: info: Dec 01 19:06:02 volumio volumio[1676]: ---------------------------- MPD announces system playlist update Dec 01 19:06:02 volumio volumio[1676]: info: Ignoring MPD Status Update Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand clear took 2 milliseconds Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand add "NAS/共享文件/音乐/王闻 - 雨丝情愁.flac" Dec 01 19:06:02 volumio volumio[1676]: error: updateQueue error: null Dec 01 19:06:02 volumio volumio[1676]: info: Dec 01 19:06:02 volumio volumio[1676]: ---------------------------- MPD announces system playlist update Dec 01 19:06:02 volumio volumio[1676]: info: Ignoring MPD Status Update Dec 01 19:06:02 volumio volumio[1676]: info: ------------------------------ 4ms Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand add "NAS/共享文件/音乐/王闻 - 雨丝情愁.flac" took 2 milliseconds Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand play Dec 01 19:06:02 volumio volumio[1676]: info: ------------------------------ 3ms Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand play took 2 milliseconds Dec 01 19:06:02 volumio volumio[1676]: info: Dec 01 19:06:02 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:02 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:02 volumio volumio[1676]: info: Dec 01 19:06:02 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand status took 8 milliseconds Dec 01 19:06:02 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand status took 3 milliseconds Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:02 volumio volumio[1676]: info: ControllerMpd::pushState Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::servicePushState Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:02 volumio volumio[1676]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"王闻 - 雨丝情愁.flac","artist":"群星","album":"试音宝典 试机二十八号","uri":"NAS/共享文件/音乐/王闻 - 雨丝情愁.flac","trackType":"flac"} Dec 01 19:06:02 volumio volumio[1676]: verbose: CURRENT POSITION 0 Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::syncState stateService play Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:06:02 volumio volumio[1676]: info: ------------------------------ 30ms Dec 01 19:06:02 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 01 19:06:02 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:02 volumio volumio[1676]: info: ControllerMpd::pushState Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::servicePushState Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:02 volumio volumio[1676]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"王闻 - 雨丝情愁.flac","artist":"群星","album":"试音宝典 试机二十八号","uri":"NAS/共享文件/音乐/王闻 - 雨丝情愁.flac","trackType":"flac"} Dec 01 19:06:02 volumio volumio[1676]: verbose: CURRENT POSITION 0 Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::syncState stateService play Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::syncState currentStatus play Dec 01 19:06:02 volumio volumio[1676]: info: Received an update from plugin. extracting info from payload Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:02 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:02 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:02 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:02 volumio volumio[1676]: info: ------------------------------ 47ms Dec 01 19:06:06 volumio volumio[1676]: info: Preload queue cleared Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::ClearQueue Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::stop Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::stPlaybackTimer Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::updateTrackBlock Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrackBlock Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::serviceStop Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::serviceStop Dec 01 19:06:06 volumio volumio[1676]: info: ControllerMpd::stop Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::clearPlayQueue Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::saveQueue Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::addQueueItems Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::addQueueItems Dec 01 19:06:06 volumio volumio[1676]: info: Preload queue cleared Dec 01 19:06:06 volumio volumio[1676]: info: Adding Item to queue: music-library/NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:06 volumio volumio[1676]: info: Using cached record of: music-library/NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::saveQueue Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::updateTrackBlock Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrackBlock Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::volumioPlay Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::play index 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::stop Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::play index undefined Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::startPlaybackTimer Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::clearAddPlayTracks NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand stop took 176 milliseconds Dec 01 19:06:06 volumio volumio[1676]: info: Dec 01 19:06:06 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:06 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand stop took 140 milliseconds Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 19:06:06 volumio volumio[1676]: info: Dec 01 19:06:06 volumio volumio[1676]: ---------------------------- MPD announces system playlist update Dec 01 19:06:06 volumio volumio[1676]: info: Ignoring MPD Status Update Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand clear took 1 milliseconds Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand add "NAS/共享文件/音乐/王闻 - 雨丝情愁.flac" Dec 01 19:06:06 volumio volumio[1676]: error: updateQueue error: null Dec 01 19:06:06 volumio volumio[1676]: info: Dec 01 19:06:06 volumio volumio[1676]: ---------------------------- MPD announces system playlist update Dec 01 19:06:06 volumio volumio[1676]: info: Ignoring MPD Status Update Dec 01 19:06:06 volumio volumio[1676]: info: ------------------------------ 3ms Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand add "NAS/共享文件/音乐/王闻 - 雨丝情愁.flac" took 3 milliseconds Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand play Dec 01 19:06:06 volumio volumio[1676]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 19:06:06 volumio volumio[1676]: info: ------------------------------ 9ms Dec 01 19:06:06 volumio volumio[1676]: info: ------------------------------ 5ms Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand play took 4 milliseconds Dec 01 19:06:06 volumio volumio[1676]: info: Dec 01 19:06:06 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:06 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:06 volumio volumio[1676]: info: Dec 01 19:06:06 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:06 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:06 volumio volumio[1676]: info: ControllerMpd::pushState Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::servicePushState Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"王闻 - 雨丝情愁.flac","artist":"群星","album":"试音宝典 试机二十八号","uri":"NAS/共享文件/音乐/王闻 - 雨丝情愁.flac","trackType":"flac"} Dec 01 19:06:06 volumio volumio[1676]: verbose: CURRENT POSITION 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::syncState stateService play Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:06:06 volumio volumio[1676]: info: ------------------------------ 12ms Dec 01 19:06:06 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 19:06:06 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:06 volumio volumio[1676]: info: ControllerMpd::pushState Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::servicePushState Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"王闻 - 雨丝情愁.flac","artist":"群星","album":"试音宝典 试机二十八号","uri":"NAS/共享文件/音乐/王闻 - 雨丝情愁.flac","trackType":"flac"} Dec 01 19:06:06 volumio volumio[1676]: verbose: CURRENT POSITION 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::syncState stateService play Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::syncState currentStatus play Dec 01 19:06:06 volumio volumio[1676]: info: Received an update from plugin. extracting info from payload Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:06 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:06 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:06 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:06 volumio volumio[1676]: info: ------------------------------ 37ms Dec 01 19:06:08 volumio volumio[1676]: info: Preload queue cleared Dec 01 19:06:08 volumio volumio[1676]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::ClearQueue Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::stop Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::stPlaybackTimer Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::updateTrackBlock Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::getTrackBlock Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:08 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:06:08 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::serviceStop Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:08 volumio volumio[1676]: info: CoreCommandRouter::serviceStop Dec 01 19:06:08 volumio volumio[1676]: info: ControllerMpd::stop Dec 01 19:06:08 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::clearPlayQueue Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::saveQueue Dec 01 19:06:08 volumio volumio[1676]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::addQueueItems Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::addQueueItems Dec 01 19:06:08 volumio volumio[1676]: info: Preload queue cleared Dec 01 19:06:08 volumio volumio[1676]: info: Adding Item to queue: music-library/NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:08 volumio volumio[1676]: info: Using cached record of: music-library/NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:08 volumio volumio[1676]: info: CoreCommandRouter::volumioPushQueue Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::saveQueue Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::updateTrackBlock Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::getTrackBlock Dec 01 19:06:08 volumio volumio[1676]: info: CoreCommandRouter::volumioPlay Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::play index 0 Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::stop Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::play index undefined Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:08 volumio volumio[1676]: info: CoreStateMachine::startPlaybackTimer Dec 01 19:06:08 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:08 volumio volumio[1676]: verbose: ControllerMpd::clearAddPlayTracks NAS/共享文件/音乐/王闻 - 雨丝情愁.flac Dec 01 19:06:08 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 19:06:09 volumio volumio[1676]: info: Dec 01 19:06:09 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand stop took 295 milliseconds Dec 01 19:06:09 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand stop took 274 milliseconds Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 19:06:09 volumio volumio[1676]: info: Dec 01 19:06:09 volumio volumio[1676]: ---------------------------- MPD announces system playlist update Dec 01 19:06:09 volumio volumio[1676]: info: Ignoring MPD Status Update Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand status took 5 milliseconds Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand clear took 5 milliseconds Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand add "NAS/共享文件/音乐/王闻 - 雨丝情愁.flac" Dec 01 19:06:09 volumio volumio[1676]: error: updateQueue error: null Dec 01 19:06:09 volumio volumio[1676]: info: Dec 01 19:06:09 volumio volumio[1676]: ---------------------------- MPD announces system playlist update Dec 01 19:06:09 volumio volumio[1676]: info: Ignoring MPD Status Update Dec 01 19:06:09 volumio volumio[1676]: info: ------------------------------ 5ms Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand add "NAS/共享文件/音乐/王闻 - 雨丝情愁.flac" took 3 milliseconds Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand play Dec 01 19:06:09 volumio volumio[1676]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 01 19:06:09 volumio volumio[1676]: info: ------------------------------ 13ms Dec 01 19:06:09 volumio volumio[1676]: info: ------------------------------ 4ms Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand play took 3 milliseconds Dec 01 19:06:09 volumio volumio[1676]: info: Dec 01 19:06:09 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:09 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:09 volumio volumio[1676]: info: Dec 01 19:06:09 volumio volumio[1676]: ---------------------------- MPD announces state update: player Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand status took 4 milliseconds Dec 01 19:06:09 volumio volumio[1676]: info: ControllerMpd::getState Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand status Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand status took 2 milliseconds Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::parseState Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:09 volumio volumio[1676]: info: ControllerMpd::pushState Dec 01 19:06:09 volumio volumio[1676]: info: CoreCommandRouter::servicePushState Dec 01 19:06:09 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:09 volumio volumio[1676]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"王闻 - 雨丝情愁.flac","artist":"群星","album":"试音宝典 试机二十八号","uri":"NAS/共享文件/音乐/王闻 - 雨丝情愁.flac","trackType":"flac"} Dec 01 19:06:09 volumio volumio[1676]: verbose: CURRENT POSITION 0 Dec 01 19:06:09 volumio volumio[1676]: info: CoreStateMachine::syncState stateService play Dec 01 19:06:09 volumio volumio[1676]: info: CoreStateMachine::syncState currentStatus stop Dec 01 19:06:09 volumio volumio[1676]: info: ------------------------------ 10ms Dec 01 19:06:09 volumio volumio[1676]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 01 19:06:09 volumio volumio[1676]: verbose: ControllerMpd::parseTrackInfo Dec 01 19:06:09 volumio volumio[1676]: info: ControllerMpd::pushState Dec 01 19:06:09 volumio volumio[1676]: info: CoreCommandRouter::servicePushState Dec 01 19:06:09 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:09 volumio volumio[1676]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"王闻 - 雨丝情愁.flac","artist":"群星","album":"试音宝典 试机二十八号","uri":"NAS/共享文件/音乐/王闻 - 雨丝情愁.flac","trackType":"flac"} Dec 01 19:06:09 volumio volumio[1676]: verbose: CURRENT POSITION 0 Dec 01 19:06:09 volumio volumio[1676]: info: CoreStateMachine::syncState stateService play Dec 01 19:06:09 volumio volumio[1676]: info: CoreStateMachine::syncState currentStatus play Dec 01 19:06:09 volumio volumio[1676]: info: Received an update from plugin. extracting info from payload Dec 01 19:06:09 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:09 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:09 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 19:06:09 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:09 volumio volumio[1676]: info: CoreStateMachine::pushState Dec 01 19:06:09 volumio volumio[1676]: info: CorePlayQueue::getTrack 0 Dec 01 19:06:09 volumio volumio[1676]: info: CoreCommandRouter::volumioPushState Dec 01 19:06:09 volumio volumio[1676]: info: ------------------------------ 33ms Dec 01 19:06:54 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 01 19:06:54 volumio volumio[1676]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 01 19:07:50 volumio volumio[1676]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 19:07:50 volumio volumio[1676]: Error: connect ETIMEDOUT 31.13.83.2:80 Dec 01 19:07:50 volumio volumio[1676]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 01 19:07:50 volumio volumio[1676]: errno: -110, Dec 01 19:07:50 volumio volumio[1676]: code: 'ETIMEDOUT', Dec 01 19:07:50 volumio volumio[1676]: syscall: 'connect', Dec 01 19:07:50 volumio volumio[1676]: address: '31.13.83.2', Dec 01 19:07:50 volumio volumio[1676]: port: 80 Dec 01 19:07:50 volumio volumio[1676]: } Dec 01 19:07:50 volumio volumio[1676]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 19:07:50 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 19:06 Dec 01 19:07:50 volumio sudo[1916]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 06:04:32 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dd58c1c2d5a97e584de8089531ce548f"