-- Logs begin at Sat 2025-05-24 21:24:50 CST, end at Sat 2025-05-24 21:42:02 CST. -- May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 24 21:41:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 21:41:10 volumio volumio[1329]: info: CoreCommandRouter::volumioGetVisibleSources May 24 21:41:10 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 21:41:10 volumio volumio[1329]: info: CoreCommandRouter::volumioGetQueue May 24 21:41:10 volumio volumio[1329]: info: CoreStateMachine::getQueue May 24 21:41:10 volumio volumio[1329]: info: CorePlayQueue::getQueue May 24 21:41:12 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 24 21:41:12 volumio volumio[1329]: info: CURURI: music-library May 24 21:41:12 volumio volumio[1329]: info: Preload queue cleared May 24 21:41:14 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 24 21:41:14 volumio volumio[1329]: info: CURURI: music-library/NAS May 24 21:41:14 volumio volumio[1329]: info: Preload queue cleared May 24 21:41:14 volumio volumio[1329]: info: Preloading song: music-library/NAS/11.后来.wav May 24 21:41:14 volumio volumio[1329]: info: Preloading song: music-library/NAS/赵鹏 - 乌兰巴托的夜晚.flac May 24 21:41:14 volumio volumio[1329]: info: Preloading song: music-library/NAS/赵鹏 - 北国之春.flac May 24 21:41:14 volumio volumio[1329]: info: Preloading song: music-library/NAS/江智民,周虹 - 有没有人告诉你.flac May 24 21:41:14 volumio volumio[1329]: info: Preloading song: music-library/NAS/区瑞强 - 偏偏喜欢你.flac May 24 21:41:14 volumio volumio[1329]: info: Preloading song: music-library/NAS/赵鹏 - 白月光.flac May 24 21:41:14 volumio volumio[1329]: info: Preloading song: music-library/NAS/蔡琴 - 恰似你的温柔.flac May 24 21:41:14 volumio volumio[1329]: info: Exploding uri music-library/NAS/11.后来.wav in service mpd May 24 21:41:14 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=609&web=//extralarge&path=%2Fmnt%2FNAS%2F11.%E5%90%8E%E6%9D%A5.wav&metadata=false May 24 21:41:14 volumio volumio[1329]: info: URI /mnt/NAS/11.后来.wav May 24 21:41:14 volumio volumio[1329]: info: Exploding uri music-library/NAS/赵鹏 - 乌兰巴托的夜晚.flac in service mpd May 24 21:41:14 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=609&web=%E8%B5%B5%E9%B9%8F/%E4%B8%AD%E5%9B%BD%E6%9C%80%E5%A5%BD%E5%A3%B0%E9%9F%B3%3A%E4%BA%BA%E5%A3%B0%E6%B5%8B%E8%AF%95%E4%B8%93%E7%94%A8%E5%A4%A7%E7%A2%9F/extralarge&path=%2Fmnt%2FNAS%2F%E8%B5%B5%E9%B9%8F%20-%20%E4%B9%8C%E5%85%B0%E5%B7%B4%E6%89%98%E7%9A%84%E5%A4%9C%E6%99%9A.flac&metadata=false May 24 21:41:14 volumio volumio[1329]: info: URI /mnt/NAS/赵鹏 - 乌兰巴托的夜晚.flac May 24 21:41:14 volumio volumio[1329]: info: Exploding uri music-library/NAS/赵鹏 - 北国之春.flac in service mpd May 24 21:41:14 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=609&web=%E8%B5%B5%E9%B9%8F/%E4%B8%AD%E5%9B%BD%E6%9C%80%E5%A5%BD%E5%A3%B0%E9%9F%B3%3A%E4%BA%BA%E5%A3%B0%E6%B5%8B%E8%AF%95%E4%B8%93%E7%94%A8%E5%A4%A7%E7%A2%9F/extralarge&path=%2Fmnt%2FNAS%2F%E8%B5%B5%E9%B9%8F%20-%20%E5%8C%97%E5%9B%BD%E4%B9%8B%E6%98%A5.flac&metadata=false May 24 21:41:14 volumio volumio[1329]: info: URI /mnt/NAS/赵鹏 - 北国之春.flac May 24 21:41:14 volumio volumio[1329]: info: Exploding uri music-library/NAS/江智民,周虹 - 有没有人告诉你.flac in service mpd May 24 21:41:14 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=609&web=%E6%B1%9F%E6%99%BA%E6%B0%91%2F%E5%91%A8%E8%99%B9/%E5%BD%93%E6%B5%81%E8%A1%8C%E9%81%87%E4%B8%8A%E5%8F%91%E7%83%A7%E6%97%B6/extralarge&path=%2Fmnt%2FNAS%2F%E6%B1%9F%E6%99%BA%E6%B0%91%2C%E5%91%A8%E8%99%B9%20-%20%E6%9C%89%E6%B2%A1%E6%9C%89%E4%BA%BA%E5%91%8A%E8%AF%89%E4%BD%A0.flac&metadata=false May 24 21:41:14 volumio volumio[1329]: info: URI /mnt/NAS/江智民,周虹 - 有没有人告诉你.flac May 24 21:41:14 volumio volumio[1329]: info: Exploding uri music-library/NAS/区瑞强 - 偏偏喜欢你.flac in service mpd May 24 21:41:14 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=609&web=%E5%8C%BA%E7%91%9E%E5%BC%BA/%E6%83%A0%E5%A8%81T200A%E8%AF%95%E9%9F%B3%E7%A2%9F/extralarge&path=%2Fmnt%2FNAS%2F%E5%8C%BA%E7%91%9E%E5%BC%BA%20-%20%E5%81%8F%E5%81%8F%E5%96%9C%E6%AC%A2%E4%BD%A0.flac&metadata=false May 24 21:41:14 volumio volumio[1329]: info: URI /mnt/NAS/区瑞强 - 偏偏喜欢你.flac May 24 21:41:14 volumio volumio[1329]: info: Exploding uri music-library/NAS/赵鹏 - 白月光.flac in service mpd May 24 21:41:14 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=609&web=%E8%B5%B5%E9%B9%8F/%E6%A2%A6%E2%80%94%E2%80%94%E7%8C%AE%E7%BB%99%E5%A4%A7%E4%BA%BA%E4%BB%AC%E7%9A%84%E6%91%87%E7%AF%AE%E6%9B%B2/extralarge&path=%2Fmnt%2FNAS%2F%E8%B5%B5%E9%B9%8F%20-%20%E7%99%BD%E6%9C%88%E5%85%89.flac&metadata=false May 24 21:41:14 volumio volumio[1329]: info: URI /mnt/NAS/赵鹏 - 白月光.flac May 24 21:41:14 volumio volumio[1329]: info: Exploding uri music-library/NAS/蔡琴 - 恰似你的温柔.flac in service mpd May 24 21:41:14 volumio volumio[1329]: info: ALBUMART /albumart?cacheid=609&web=%E8%94%A1%E7%90%B4/%E7%B2%BE%E9%80%89%E8%94%A1%E7%90%B4/extralarge&path=%2Fmnt%2FNAS%2F%E8%94%A1%E7%90%B4%20-%20%E6%81%B0%E4%BC%BC%E4%BD%A0%E7%9A%84%E6%B8%A9%E6%9F%94.flac&metadata=false May 24 21:41:14 volumio volumio[1329]: info: URI /mnt/NAS/蔡琴 - 恰似你的温柔.flac May 24 21:41:16 volumio volumio[1329]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 24 21:41:16 volumio volumio[1329]: info: Retrying Login Due to Network Error May 24 21:41:18 volumio volumio[1329]: info: Preload queue cleared May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::ClearQueue May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::stop May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::serviceStop May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::getTrack 2 May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::serviceStop May 24 21:41:18 volumio volumio[1329]: info: ControllerMpd::stop May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::clearPlayQueue May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::saveQueue May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::volumioPushQueue May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::addQueueItems May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::addQueueItems May 24 21:41:18 volumio volumio[1329]: info: Preload queue cleared May 24 21:41:18 volumio volumio[1329]: info: Adding Item to queue: music-library/NAS/11.后来.wav May 24 21:41:18 volumio volumio[1329]: info: Using cached record of: music-library/NAS/11.后来.wav May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::volumioPushQueue May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::saveQueue May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::volumioPlay May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::play index 0 May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::addQueueItems May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::addQueueItems May 24 21:41:18 volumio volumio[1329]: info: Preload queue cleared May 24 21:41:18 volumio volumio[1329]: info: Adding Item to queue: music-library/NAS/赵鹏 - 乌兰巴托的夜晚.flac May 24 21:41:18 volumio volumio[1329]: info: Using cached record of: music-library/NAS/赵鹏 - 乌兰巴托的夜晚.flac May 24 21:41:18 volumio volumio[1329]: info: Adding Item to queue: music-library/NAS/赵鹏 - 北国之春.flac May 24 21:41:18 volumio volumio[1329]: info: Using cached record of: music-library/NAS/赵鹏 - 北国之春.flac May 24 21:41:18 volumio volumio[1329]: info: Adding Item to queue: music-library/NAS/江智民,周虹 - 有没有人告诉你.flac May 24 21:41:18 volumio volumio[1329]: info: Using cached record of: music-library/NAS/江智民,周虹 - 有没有人告诉你.flac May 24 21:41:18 volumio volumio[1329]: info: Adding Item to queue: music-library/NAS/区瑞强 - 偏偏喜欢你.flac May 24 21:41:18 volumio volumio[1329]: info: Using cached record of: music-library/NAS/区瑞强 - 偏偏喜欢你.flac May 24 21:41:18 volumio volumio[1329]: info: Adding Item to queue: music-library/NAS/赵鹏 - 白月光.flac May 24 21:41:18 volumio volumio[1329]: info: Using cached record of: music-library/NAS/赵鹏 - 白月光.flac May 24 21:41:18 volumio volumio[1329]: info: Adding Item to queue: music-library/NAS/蔡琴 - 恰似你的温柔.flac May 24 21:41:18 volumio volumio[1329]: info: Using cached record of: music-library/NAS/蔡琴 - 恰似你的温柔.flac May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::stop May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 21:41:18 volumio volumio[1329]: info: CoreCommandRouter::volumioPushQueue May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::saveQueue May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::play index undefined May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:18 volumio volumio[1329]: info: CoreStateMachine::startPlaybackTimer May 24 21:41:18 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::clearAddPlayTracks NAS/11.后来.wav May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop May 24 21:41:18 volumio volumio[1329]: info: May 24 21:41:18 volumio volumio[1329]: ---------------------------- MPD announces state update: player May 24 21:41:18 volumio volumio[1329]: info: sendMpdCommand stop took 124 milliseconds May 24 21:41:18 volumio volumio[1329]: info: ControllerMpd::getState May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status May 24 21:41:18 volumio volumio[1329]: info: sendMpdCommand stop took 14 milliseconds May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear May 24 21:41:18 volumio volumio[1329]: info: May 24 21:41:18 volumio volumio[1329]: ---------------------------- MPD announces system playlist update May 24 21:41:18 volumio volumio[1329]: info: Ignoring MPD Status Update May 24 21:41:18 volumio volumio[1329]: info: sendMpdCommand status took 6 milliseconds May 24 21:41:18 volumio volumio[1329]: info: sendMpdCommand clear took 5 milliseconds May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::parseState May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "NAS/11.后来.wav" May 24 21:41:18 volumio volumio[1329]: error: updateQueue error: null May 24 21:41:18 volumio volumio[1329]: info: May 24 21:41:18 volumio volumio[1329]: ---------------------------- MPD announces system playlist update May 24 21:41:18 volumio volumio[1329]: info: Ignoring MPD Status Update May 24 21:41:18 volumio volumio[1329]: info: ------------------------------ 8ms May 24 21:41:18 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 8 milliseconds May 24 21:41:18 volumio volumio[1329]: info: sendMpdCommand add "NAS/11.后来.wav" took 7 milliseconds May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo May 24 21:41:18 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand play May 24 21:41:18 volumio volumio[1329]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 21:41:19 volumio volumio[1329]: info: ------------------------------ 20ms May 24 21:41:19 volumio volumio[1329]: info: May 24 21:41:19 volumio volumio[1329]: ---------------------------- MPD announces state update: player May 24 21:41:19 volumio volumio[1329]: info: ------------------------------ 14ms May 24 21:41:19 volumio volumio[1329]: info: sendMpdCommand play took 12 milliseconds May 24 21:41:19 volumio volumio[1329]: info: ControllerMpd::getState May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status May 24 21:41:19 volumio volumio[1329]: info: May 24 21:41:19 volumio volumio[1329]: ---------------------------- MPD announces state update: player May 24 21:41:19 volumio volumio[1329]: info: sendMpdCommand status took 311 milliseconds May 24 21:41:19 volumio volumio[1329]: info: ControllerMpd::getState May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::parseState May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 21:41:19 volumio volumio[1329]: info: sendMpdCommand status took 7 milliseconds May 24 21:41:19 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 8 milliseconds May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::parseState May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo May 24 21:41:19 volumio volumio[1329]: info: ControllerMpd::pushState May 24 21:41:19 volumio volumio[1329]: info: CoreCommandRouter::servicePushState May 24 21:41:19 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:19 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.后来.wav","artist":null,"album":null,"uri":"NAS/11.后来.wav","trackType":"wav"} May 24 21:41:19 volumio volumio[1329]: verbose: CURRENT POSITION 0 May 24 21:41:19 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play May 24 21:41:19 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop May 24 21:41:19 volumio volumio[1329]: info: ------------------------------ 328ms May 24 21:41:19 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 3 milliseconds May 24 21:41:19 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo May 24 21:41:19 volumio volumio[1329]: info: ControllerMpd::pushState May 24 21:41:19 volumio volumio[1329]: info: CoreCommandRouter::servicePushState May 24 21:41:19 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:19 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.后来.wav","artist":null,"album":null,"uri":"NAS/11.后来.wav","trackType":"wav"} May 24 21:41:19 volumio volumio[1329]: verbose: CURRENT POSITION 0 May 24 21:41:19 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play May 24 21:41:19 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play May 24 21:41:19 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload May 24 21:41:19 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:19 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:19 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 21:41:19 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:19 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:19 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:19 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:19 volumio volumio[1329]: info: ------------------------------ 121ms May 24 21:41:21 volumio volumio[1329]: error: error May 24 21:41:26 volumio volumio[1329]: info: MyVolumio login type: Token May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::volumioNext May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::next May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::stop May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::serviceStop May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 0 May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::serviceStop May 24 21:41:49 volumio volumio[1329]: info: ControllerMpd::stop May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop May 24 21:41:49 volumio volumio[1329]: info: May 24 21:41:49 volumio volumio[1329]: ---------------------------- MPD announces state update: player May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand stop took 23 milliseconds May 24 21:41:49 volumio volumio[1329]: info: ControllerMpd::getState May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::play index undefined May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::startPlaybackTimer May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::clearAddPlayTracks NAS/赵鹏 - 乌兰巴托的夜晚.flac May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::updateTrackBlock May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrackBlock May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand status took 17 milliseconds May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand stop took 6 milliseconds May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::parseState May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear May 24 21:41:49 volumio volumio[1329]: info: May 24 21:41:49 volumio volumio[1329]: ---------------------------- MPD announces system playlist update May 24 21:41:49 volumio volumio[1329]: info: Ignoring MPD Status Update May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 6 milliseconds May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand clear took 6 milliseconds May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "NAS/赵鹏 - 乌兰巴托的夜晚.flac" May 24 21:41:49 volumio volumio[1329]: info: ControllerMpd::pushState May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::servicePushState May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: 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":"11.后来.wav","artist":null,"album":null,"uri":"NAS/11.后来.wav","trackType":"wav"} May 24 21:41:49 volumio volumio[1329]: verbose: CURRENT POSITION 1 May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::syncState stateService stop May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:49 volumio volumio[1329]: info: No code May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:49 volumio volumio[1329]: info: ------------------------------ 81ms May 24 21:41:49 volumio volumio[1329]: error: updateQueue error: null May 24 21:41:49 volumio volumio[1329]: info: May 24 21:41:49 volumio volumio[1329]: ---------------------------- MPD announces system playlist update May 24 21:41:49 volumio volumio[1329]: info: Ignoring MPD Status Update May 24 21:41:49 volumio volumio[1329]: info: ------------------------------ 60ms May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand add "NAS/赵鹏 - 乌兰巴托的夜晚.flac" took 58 milliseconds May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand play May 24 21:41:49 volumio volumio[1329]: info: May 24 21:41:49 volumio volumio[1329]: ---------------------------- MPD announces state update: player May 24 21:41:49 volumio volumio[1329]: info: ------------------------------ 45ms May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand play took 43 milliseconds May 24 21:41:49 volumio volumio[1329]: info: ControllerMpd::getState May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status May 24 21:41:49 volumio volumio[1329]: info: May 24 21:41:49 volumio volumio[1329]: ---------------------------- MPD announces state update: player May 24 21:41:49 volumio volumio[1329]: info: ControllerMpd::getState May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand status took 20 milliseconds May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::parseState May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand status took 20 milliseconds May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 19 milliseconds May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::parseState May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo May 24 21:41:49 volumio volumio[1329]: info: ControllerMpd::pushState May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::servicePushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"687 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"NAS/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} May 24 21:41:49 volumio volumio[1329]: verbose: CURRENT POSITION 1 May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus stop May 24 21:41:49 volumio volumio[1329]: info: ------------------------------ 51ms May 24 21:41:49 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 13 milliseconds May 24 21:41:49 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo May 24 21:41:49 volumio volumio[1329]: info: ControllerMpd::pushState May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::servicePushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"NAS/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} May 24 21:41:49 volumio volumio[1329]: verbose: CURRENT POSITION 1 May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::syncState stateService play May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus play May 24 21:41:49 volumio volumio[1329]: info: Received an update from plugin. extracting info from payload May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:49 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:41:49 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:41:49 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:41:49 volumio volumio[1329]: info: ------------------------------ 94ms May 24 21:41:56 volumio volumio[1329]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 24 21:41:56 volumio volumio[1329]: info: Retrying Login Due to Network Error May 24 21:41:59 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 21:41:59 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 24 21:41:59 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 21:41:59 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 24 21:42:00 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 21:42:01 volumio volumio[1329]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 24 21:42:01 volumio volumio[1329]: info: Preparing to save Alsa Options, stopping services first May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::volumioGetState May 24 21:42:01 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::volumioPause May 24 21:42:01 volumio volumio[1329]: info: CoreStateMachine::pause May 24 21:42:01 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer May 24 21:42:01 volumio volumio[1329]: info: CoreStateMachine::servicePause May 24 21:42:01 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::servicePause May 24 21:42:01 volumio volumio[1329]: info: ControllerMpd::pause May 24 21:42:01 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand pause May 24 21:42:01 volumio volumio[1329]: info: Saving Audio Output to: {"output_device":{"value":"1,1","label":"SPDIF"}} May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 21:42:01 volumio volumio[1329]: info: Found match in Cards Database: setting mixer for card SPDIF May 24 21:42:01 volumio volumio[1329]: info: Setting default mixerSoftMaster for Softvolume device May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 21:42:01 volumio volumio[1329]: info: Updating Volume Controller Parameters: Device: 1,1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 24 21:42:01 volumio volumio[1329]: info: Disabling external Volume Control May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 21:42:01 volumio volumio[1329]: info: Enable softmixer device for audio device undefined May 24 21:42:01 volumio volumio[1329]: info: May 24 21:42:01 volumio volumio[1329]: ---------------------------- MPD announces state update: player May 24 21:42:01 volumio volumio[1329]: info: sendMpdCommand pause took 268 milliseconds May 24 21:42:01 volumio volumio[1329]: info: ControllerMpd::getState May 24 21:42:01 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand status May 24 21:42:01 volumio volumio[1329]: info: sendMpdCommand status took 4 milliseconds May 24 21:42:01 volumio volumio[1329]: verbose: ControllerMpd::parseState May 24 21:42:01 volumio volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 21:42:01 volumio volumio[1329]: info: Software Volume ALSA configuration written May 24 21:42:01 volumio volumio[1329]: info: Preparing to generate the ALSA configuration file May 24 21:42:01 volumio volumio[1329]: info: sendMpdCommand playlistinfo took 26 milliseconds May 24 21:42:01 volumio volumio[1329]: verbose: ControllerMpd::parseTrackInfo May 24 21:42:01 volumio volumio[1329]: info: ControllerMpd::pushState May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::servicePushState May 24 21:42:01 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:42:01 volumio volumio[1329]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13067,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"945 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"NAS/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} May 24 21:42:01 volumio volumio[1329]: verbose: CURRENT POSITION 1 May 24 21:42:01 volumio volumio[1329]: info: CoreStateMachine::syncState stateService pause May 24 21:42:01 volumio volumio[1329]: info: CoreStateMachine::syncState currentStatus pause May 24 21:42:01 volumio volumio[1329]: info: CoreStateMachine::pushState May 24 21:42:01 volumio volumio[1329]: info: CorePlayQueue::getTrack 1 May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::volumioPushState May 24 21:42:01 volumio volumio[1329]: info: CoreStateMachine::stPlaybackTimer May 24 21:42:01 volumio volumio[1329]: info: ------------------------------ 65ms May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 24 21:42:01 volumio volumio[1329]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 21:42:01 volumio volumio[1329]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 24 21:42:01 volumio volumio[1329]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 24 21:42:01 volumio volumio[1329]: info: Reading ALSA contributions from plugins. May 24 21:42:01 volumio volumio[1329]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 21:42:01 volumio volumio[1329]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' May 24 21:42:01 volumio volumio[1329]: at Object.readdirSync (fs.js:1048:3) May 24 21:42:01 volumio volumio[1329]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) May 24 21:42:01 volumio volumio[1329]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) May 24 21:42:01 volumio volumio[1329]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) May 24 21:42:01 volumio volumio[1329]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) May 24 21:42:01 volumio volumio[1329]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) May 24 21:42:01 volumio volumio[1329]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 24 21:42:01 volumio volumio[1329]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) May 24 21:42:01 volumio volumio[1329]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) May 24 21:42:01 volumio volumio[1329]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) May 24 21:42:01 volumio volumio[1329]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 24 21:42:01 volumio volumio[1329]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) May 24 21:42:01 volumio volumio[1329]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) May 24 21:42:01 volumio volumio[1329]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 24 21:42:01 volumio volumio[1329]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) May 24 21:42:01 volumio volumio[1329]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { May 24 21:42:01 volumio volumio[1329]: errno: -20, May 24 21:42:01 volumio volumio[1329]: syscall: 'scandir', May 24 21:42:01 volumio volumio[1329]: code: 'ENOTDIR', May 24 21:42:01 volumio volumio[1329]: path: '/tmp/peppyrunning' May 24 21:42:01 volumio volumio[1329]: } May 24 21:42:01 volumio volumio[1329]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 21:42:02 volumio sudo[6532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 21:41 May 24 21:42:02 volumio sudo[6532]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"