-- Logs begin at Sat 2024-08-17 10:32:12 UTC, end at Sat 2024-08-17 10:37:59 UTC. -- Aug 17 10:36:07 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 10:36:07 volumio volumio[883]: info: CURURI: artists://%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B Aug 17 10:36:07 volumio volumio[883]: info: Preload queue cleared Aug 17 10:36:07 volumio volumio[883]: info: Preload queue cleared Aug 17 10:36:07 volumio volumio[883]: info: Preloading song: music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:07 volumio volumio[883]: info: Preloading song: music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/刻盘/2010年会音乐/公司音乐/节目/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:07 volumio volumio[883]: info: Exploding uri music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv in service mpd Aug 17 10:36:07 volumio volumio[883]: info: ALBUMART /albumart?cacheid=338&web=%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B//extralarge&path=%2Fmnt%2FNAS%2FNAS%2Fgu2024%2FGU%2F2016-4-20%2F%E8%A1%8C%E6%94%BF%2F2010%2F2010%E5%B9%B4%E4%BC%9A%E9%9F%B3%E4%B9%90%2F%E8%8A%82%E7%9B%AE%E9%9F%B3%E4%B9%90%2F%E8%8A%82%E7%9B%AE%E5%85%AB%20%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B_%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B.wmv&metadata=false Aug 17 10:36:07 volumio volumio[883]: info: URI /mnt/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:07 volumio volumio[883]: info: No valid Plugin REST Endpoint Aug 17 10:36:07 volumio volumio[883]: info: Exploding uri music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/刻盘/2010年会音乐/公司音乐/节目/节目八 一千零一个愿望_一千零一个愿望.wmv in service mpd Aug 17 10:36:07 volumio volumio[883]: info: ALBUMART /albumart?cacheid=338&web=%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B//extralarge&path=%2Fmnt%2FNAS%2FNAS%2Fgu2024%2FGU%2F2016-4-20%2F%E8%A1%8C%E6%94%BF%2F2010%2F2010%E5%B9%B4%E4%BC%9A%E9%9F%B3%E4%B9%90%2F%E5%88%BB%E7%9B%98%2F2010%E5%B9%B4%E4%BC%9A%E9%9F%B3%E4%B9%90%2F%E5%85%AC%E5%8F%B8%E9%9F%B3%E4%B9%90%2F%E8%8A%82%E7%9B%AE%2F%E8%8A%82%E7%9B%AE%E5%85%AB%20%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B_%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B.wmv&metadata=false Aug 17 10:36:07 volumio volumio[883]: info: URI /mnt/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/刻盘/2010年会音乐/公司音乐/节目/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:08 volumio volumio[883]: info: Preload queue cleared Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::ClearQueue Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::stop Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrackBlock Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::serviceStop Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::serviceStop Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::stop Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::clearPlayQueue Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::saveQueue Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushQueue Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::addQueueItems Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::addQueueItems Aug 17 10:36:08 volumio volumio[883]: info: Preload queue cleared Aug 17 10:36:08 volumio volumio[883]: info: Adding Item to queue: music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:08 volumio volumio[883]: info: Using cached record of: music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:08 volumio volumio[883]: info: Adding Item to queue: music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/刻盘/2010年会音乐/公司音乐/节目/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:08 volumio volumio[883]: info: Using cached record of: music-library/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/刻盘/2010年会音乐/公司音乐/节目/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushQueue Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::saveQueue Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrackBlock Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::play index 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::stop Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::play index undefined Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand stop took 9 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand stop took 2 milliseconds Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces system playlist update Aug 17 10:36:08 volumio volumio[883]: info: Ignoring MPD Status Update Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand clear took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 0 milliseconds Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv" Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService stop Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: No code Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService stop Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: No code Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 17ms Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 17ms Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces system playlist update Aug 17 10:36:08 volumio volumio[883]: info: Ignoring MPD Status Update Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces system playlist update Aug 17 10:36:08 volumio volumio[883]: info: Ignoring MPD Status Update Aug 17 10:36:08 volumio volumio[883]: error: updateQueue error: null Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces system playlist update Aug 17 10:36:08 volumio volumio[883]: info: Ignoring MPD Status Update Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 20ms Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand add "NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv" took 19 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 3ms Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play Aug 17 10:36:08 volumio volumio[883]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 24ms Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces system playlist update Aug 17 10:36:08 volumio volumio[883]: info: Ignoring MPD Status Update Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces system playlist update Aug 17 10:36:08 volumio volumio[883]: info: Ignoring MPD Status Update Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 4ms Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand play took 4 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 1ms Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 1ms Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: Aug 17 10:36:08 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand status took 0 milliseconds Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:08 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:08 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 6ms Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 14ms Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 13ms Aug 17 10:36:08 volumio volumio[883]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 17 10:36:08 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:08 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:08 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:08 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:08 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:08 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:08 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 52ms Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 53ms Aug 17 10:36:08 volumio volumio[883]: info: ------------------------------ 52ms Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::volumioSeek Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::seek Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","service":"mpd","name":"一千零一个愿望","artist":"一千零一个愿望","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=338&web=%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B//extralarge&path=%2FNAS%2FNAS%2Fgu2024%2FGU%2F2016-4-20%2F%E8%A1%8C%E6%94%BF%2F2010%2F2010%E5%B9%B4%E4%BC%9A%E9%9F%B3%E4%B9%90%2F%E8%8A%82%E7%9B%AE%E9%9F%B3%E4%B9%90&metadata=false","duration":259,"samplerate":44.1,"bitdepth":"32 bit","trackType":"wmv","channels":2} Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: info: ControllerMpd::seek Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:12 volumio volumio[883]: error: null Aug 17 10:36:12 volumio volumio[883]: info: Aug 17 10:36:12 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:12 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:12 volumio volumio[883]: info: Aug 17 10:36:12 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:12 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:12 volumio volumio[883]: info: Aug 17 10:36:12 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:12 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:12 volumio volumio[883]: info: sendMpdCommand status took 4 milliseconds Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:12 volumio volumio[883]: info: sendMpdCommand status took 5 milliseconds Aug 17 10:36:12 volumio volumio[883]: info: sendMpdCommand status took 5 milliseconds Aug 17 10:36:12 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:12 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30812,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:12 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:12 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:12 volumio volumio[883]: info: ------------------------------ 10ms Aug 17 10:36:12 volumio volumio[883]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 17 10:36:12 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:12 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30823,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:12 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:12 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:12 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:12 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:12 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:13 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:13 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:13 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:13 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:13 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:13 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:13 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30834,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:13 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:13 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:13 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:13 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:13 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:13 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:13 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:13 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:13 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:13 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:13 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:13 volumio volumio[883]: info: ------------------------------ 48ms Aug 17 10:36:13 volumio volumio[883]: info: ------------------------------ 49ms Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioSeek Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::seek Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: TRACKBLOCK {"uri":"mnt/NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","service":"mpd","name":"一千零一个愿望","artist":"一千零一个愿望","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=338&web=%E4%B8%80%E5%8D%83%E9%9B%B6%E4%B8%80%E4%B8%AA%E6%84%BF%E6%9C%9B//extralarge&path=%2FNAS%2FNAS%2Fgu2024%2FGU%2F2016-4-20%2F%E8%A1%8C%E6%94%BF%2F2010%2F2010%E5%B9%B4%E4%BC%9A%E9%9F%B3%E4%B9%90%2F%E8%8A%82%E7%9B%AE%E9%9F%B3%E4%B9%90&metadata=false","duration":259,"samplerate":44.1,"bitdepth":"32 bit","trackType":"wmv","channels":2} Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: ControllerMpd::seek Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:18 volumio volumio[883]: error: null Aug 17 10:36:18 volumio volumio[883]: info: Aug 17 10:36:18 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:18 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:18 volumio volumio[883]: info: Aug 17 10:36:18 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:18 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:18 volumio volumio[883]: info: Aug 17 10:36:18 volumio volumio[883]: ---------------------------- MPD announces state update: player Aug 17 10:36:18 volumio volumio[883]: info: ControllerMpd::getState Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Aug 17 10:36:18 volumio volumio[883]: info: sendMpdCommand status took 10 milliseconds Aug 17 10:36:18 volumio volumio[883]: info: sendMpdCommand status took 10 milliseconds Aug 17 10:36:18 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::parseState Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 17 10:36:18 volumio volumio[883]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 10:36:18 volumio volumio[883]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 10:36:18 volumio volumio[883]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:18 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Aug 17 10:36:18 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":88029,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:18 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:18 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:18 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":88029,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:18 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:18 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:18 volumio volumio[883]: info: ControllerMpd::pushState Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::servicePushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":88029,"duration":259,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"一千零一个愿望","album":null,"uri":"NAS/NAS/gu2024/GU/2016-4-20/行政/2010/2010年会音乐/节目音乐/节目八 一千零一个愿望_一千零一个愿望.wmv","trackType":"wmv"} Aug 17 10:36:18 volumio volumio[883]: verbose: CURRENT POSITION 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Aug 17 10:36:18 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:18 volumio volumio[883]: info: CoreStateMachine::pushState Aug 17 10:36:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Aug 17 10:36:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Aug 17 10:36:18 volumio volumio[883]: info: ------------------------------ 27ms Aug 17 10:36:18 volumio volumio[883]: info: ------------------------------ 26ms Aug 17 10:36:18 volumio volumio[883]: info: ------------------------------ 17ms Aug 17 10:36:38 volumio kernel: usb 1-2: USB disconnect, device number 2 Aug 17 10:36:38 volumio kernel: usb 1-2.2: USB disconnect, device number 4 Aug 17 10:36:38 volumio thd[642]: Error reading device '/dev/input/event4' Aug 17 10:36:39 volumio thd[642]: Error reading device '/dev/input/event5' Aug 17 10:36:39 volumio thd[642]: Error reading device '/dev/input/event6' Aug 17 10:36:39 volumio kernel: usb 1-2.4: USB disconnect, device number 5 Aug 17 10:36:39 volumio thd[642]: Error reading device '/dev/input/event8' Aug 17 10:36:51 volumio kernel: usb 1-5: new full-speed USB device number 6 using xhci_hcd Aug 17 10:36:51 volumio kernel: usb 1-5: New USB device found, idVendor=1915, idProduct=1025, bcdDevice= 1.00 Aug 17 10:36:51 volumio kernel: usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 17 10:36:51 volumio kernel: usb 1-5: Product: USB Composite Device Aug 17 10:36:51 volumio kernel: usb 1-5: Manufacturer: XING WEI 2.4G USB Aug 17 10:36:51 volumio kernel: input: XING WEI 2.4G USB USB Composite Device as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.2/0003:1915:1025.0005/input/input15 Aug 17 10:36:51 volumio kernel: hid-generic 0003:1915:1025.0005: input,hidraw1: USB HID v1.10 Keyboard [XING WEI 2.4G USB USB Composite Device] on usb-0000:00:14.0-5/input2 Aug 17 10:36:51 volumio kernel: input: XING WEI 2.4G USB USB Composite Device Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.3/0003:1915:1025.0006/input/input16 Aug 17 10:36:51 volumio kernel: input: XING WEI 2.4G USB USB Composite Device Consumer Control as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.3/0003:1915:1025.0006/input/input17 Aug 17 10:36:52 volumio kernel: input: XING WEI 2.4G USB USB Composite Device System Control as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.3/0003:1915:1025.0006/input/input18 Aug 17 10:36:52 volumio kernel: hid-generic 0003:1915:1025.0006: input,hidraw2: USB HID v1.10 Mouse [XING WEI 2.4G USB USB Composite Device] on usb-0000:00:14.0-5/input3 Aug 17 10:36:52 volumio systemd-udevd[2310]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 99. Aug 17 10:36:52 volumio volumio[883]: info: No valid Plugin REST Endpoint Aug 17 10:36:52 volumio systemd-udevd[2318]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:36:52 volumio systemd-udevd[2313]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:36:52 volumio systemd-logind[644]: Watching system buttons on /dev/input/event4 (XING WEI 2.4G USB USB Composite Device) Aug 17 10:36:52 volumio systemd-udevd[2325]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:36:52 volumio systemd-logind[644]: Watching system buttons on /dev/input/event8 (XING WEI 2.4G USB USB Composite Device System Control) Aug 17 10:36:52 volumio systemd-udevd[2310]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:36:52 volumio systemd-udevd[2312]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:36:52 volumio systemd-logind[644]: Watching system buttons on /dev/input/event6 (XING WEI 2.4G USB USB Composite Device Consumer Control) Aug 17 10:37:12 volumio volumio[883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 10:37:12 volumio volumio[883]: Error: connect ETIMEDOUT 199.16.156.11:80 Aug 17 10:37:12 volumio volumio[883]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 17 10:37:12 volumio volumio[883]: errno: -110, Aug 17 10:37:12 volumio volumio[883]: code: 'ETIMEDOUT', Aug 17 10:37:12 volumio volumio[883]: syscall: 'connect', Aug 17 10:37:12 volumio volumio[883]: address: '199.16.156.11', Aug 17 10:37:12 volumio volumio[883]: port: 80 Aug 17 10:37:12 volumio volumio[883]: } Aug 17 10:37:12 volumio volumio[883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 10:37:12 volumio sudo[2387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 10:36 Aug 17 10:37:12 volumio sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:12 volumio sudo[2387]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:15 volumio volumio-remote-updater[656]: [2024-08-17 10:37:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 17 10:37:15 volumio volumio-remote-updater[656]: [2024-08-17 10:37:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 17 10:37:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 17 10:37:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 17 10:37:15 volumio systemd[1]: Started dynamicswap service. Aug 17 10:37:15 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 17 10:37:15 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 17 10:37:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Aug 17 10:37:15 volumio systemd[1]: Stopped Volumio Backend Module. Aug 17 10:37:15 volumio systemd[1]: Started Volumio Backend Module. Aug 17 10:37:15 volumio systemd[1]: Started dynamicswap service. Aug 17 10:37:15 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 17 10:37:16 volumio volumio[2446]: info: ------------------------------------------- Aug 17 10:37:16 volumio volumio[2446]: info: ----- Volumio3 ---- Aug 17 10:37:16 volumio volumio[2446]: info: ------------------------------------------- Aug 17 10:37:16 volumio volumio[2446]: info: ----- System startup ---- Aug 17 10:37:16 volumio volumio[2446]: info: ------------------------------------------- Aug 17 10:37:16 volumio volumio[2446]: info: MYVOLUMIO Environment detected Aug 17 10:37:16 volumio volumio[2446]: info: Plugin folders cleanup Aug 17 10:37:16 volumio volumio[2446]: info: Scanning into folder /volumio/app/plugins/ Aug 17 10:37:16 volumio volumio[2446]: info: Scanning category audio_interface Aug 17 10:37:16 volumio volumio[2446]: info: Scanning category miscellanea Aug 17 10:37:16 volumio volumio[2446]: info: Scanning category music_service Aug 17 10:37:16 volumio volumio[2446]: info: Scanning category plugins.json Aug 17 10:37:16 volumio volumio[2446]: info: Scanning category system_controller Aug 17 10:37:16 volumio volumio[2446]: info: Scanning category user_interface Aug 17 10:37:16 volumio volumio[2446]: info: Scanning into folder /data/plugins/ Aug 17 10:37:16 volumio volumio[2446]: info: Plugin folders cleanup completed Aug 17 10:37:16 volumio volumio[2446]: info: ------------------------------------------- Aug 17 10:37:16 volumio volumio[2446]: info: ----- Core plugins startup ---- Aug 17 10:37:16 volumio volumio[2446]: info: ------------------------------------------- Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugins from folder /volumio/app/plugins/ Aug 17 10:37:16 volumio volumio[2446]: info: Adding plugin upnp to MyMusic Plugins Aug 17 10:37:16 volumio volumio[2446]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 17 10:37:16 volumio volumio[2446]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugins from folder /data/plugins/ Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "system"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "appearance"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "network"... Aug 17 10:37:16 volumio volumio[2446]: info: Refreshing Cached IP Addresses Aug 17 10:37:16 volumio sudo[2473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 17 10:37:16 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:16 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:16 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 17 10:37:16 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "services"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "alsa_controller"... Aug 17 10:37:16 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 17 10:37:16 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:16 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:16 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "wizard"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "networkfs"... Aug 17 10:37:16 volumio volumio[2446]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Aug 17 10:37:16 volumio volumio[2446]: info: Starting Udev Watcher for removable devices Aug 17 10:37:16 volumio volumio[2446]: info: Ignoring mount for partition: boot Aug 17 10:37:16 volumio volumio[2446]: info: Ignoring mount for partition: volumio Aug 17 10:37:16 volumio volumio[2446]: info: Ignoring mount for partition: volumio_data Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "volumio_command_line_client"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "upnp"... Aug 17 10:37:16 volumio volumio[2446]: info: [1723891036534] Starting Upmpd Daemon Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "my_music"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "mpd"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "upnp_browser"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "alarm-clock"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "airplay_emulation"... Aug 17 10:37:16 volumio volumio[2446]: info: Starting Shairport Sync Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "last_100"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "webradio"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "i2s_dacs"... Aug 17 10:37:16 volumio volumio[2446]: info: I2S DAC not set, start Auto-detection Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "volumiodiscovery"... Aug 17 10:37:16 volumio volumio[2446]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 17 10:37:16 volumio node[2446]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 17 10:37:16 volumio volumio[2446]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 10:37:16 volumio node[2446]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 10:37:16 volumio volumio[2446]: *** WARNING *** For more information see Aug 17 10:37:16 volumio node[2446]: *** WARNING *** For more information see Aug 17 10:37:16 volumio volumio[2446]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 17 10:37:16 volumio node[2446]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 17 10:37:16 volumio volumio[2446]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 10:37:16 volumio node[2446]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 17 10:37:16 volumio volumio[2446]: *** WARNING *** For more information see Aug 17 10:37:16 volumio node[2446]: *** WARNING *** For more information see Aug 17 10:37:16 volumio volumio[2446]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 17 10:37:16 volumio volumio[2446]: info: Discovery: Started advertising with name: Volumio Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "outputs"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "albumart"... Aug 17 10:37:16 volumio volumio[2446]: info: Plugin example_plugin is not enabled Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "inputs"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "updater_comm"... Aug 17 10:37:16 volumio volumio[2446]: info: Plugin mpdemulation is not enabled Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "rest_api"... Aug 17 10:37:16 volumio volumio[2446]: info: Loading plugin "websocket"... Aug 17 10:37:16 volumio volumio[2446]: Forking 1 albumart workers Aug 17 10:37:16 volumio volumio[2446]: info: Starting Socket.io Server version 2.3.0 Aug 17 10:37:16 volumio volumio[2446]: info: Loading i18n strings for locale zh Aug 17 10:37:16 volumio volumio[2446]: Updating browse sources language Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::initPlayerControls Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: Express server listening on port 3000 Aug 17 10:37:16 volumio volumio[2446]: [Metrics] WebUI: 0s 889.77ms Aug 17 10:37:16 volumio volumio[2446]: info: Setting Device type: x86 Aug 17 10:37:16 volumio volumio[2446]: info: CoreStateMachine::resetVolumioState Aug 17 10:37:16 volumio volumio[2446]: info: CoreStateMachine::getcurrentVolume Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::volumioRetrievevolume Aug 17 10:37:16 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:16 volumio volumio[2446]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 17 10:37:16 volumio volumio[2446]: info: Completed loading Core Plugins Aug 17 10:37:16 volumio volumio[2446]: info: Preparing to generate the ALSA configuration file Aug 17 10:37:16 volumio volumio[2446]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 17 10:37:16 volumio volumio[2446]: wlan0 Interface doesn't support scanning. Aug 17 10:37:16 volumio volumio[2446]: info: Cannot use regular scanning, forcing with ap-force Aug 17 10:37:16 volumio volumio[2446]: Starting albumart workers Aug 17 10:37:16 volumio sudo[2507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 17 10:37:16 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:16 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:16 volumio volumio[2446]: command failed: No such device (-19) Aug 17 10:37:16 volumio volumio[2446]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Aug 17 10:37:16 volumio volumio[2446]: command failed: No such device (-19) Aug 17 10:37:16 volumio volumio[2446]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 17 10:37:17 volumio volumio[2446]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 10:37:17 volumio volumio[2446]: info: Reloading queue from file Aug 17 10:37:17 volumio volumio[2446]: info: VolumeController:: Volume=44 Mute =false Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::pushState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioPushState Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::updateTrackBlock Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrackBlock Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioRetrievevolume Aug 17 10:37:17 volumio volumio[2446]: info: Asound.conf file unchanged, so no further update is needed Aug 17 10:37:17 volumio volumio[2446]: info: Output device has changed, restarting MPD Aug 17 10:37:17 volumio volumio[2446]: info: Output device has changed, restarting Shairport Sync Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:17 volumio sudo[2528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 10:37:17 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:17 volumio sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 10:37:17 volumio sudo[2528]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:17 volumio sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:17 volumio systemd[1]: Stopping Music Player Daemon... Aug 17 10:37:17 volumio volumio[2446]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 10:37:17 volumio volumio[2446]: info: ___________ START PLUGINS ___________ Aug 17 10:37:17 volumio volumio[2446]: info: ControllerMpd::onStart: Initializing MPD Aug 17 10:37:17 volumio volumio[2446]: info: Creating MPD Configuration file Aug 17 10:37:17 volumio sudo[2535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 10:37:17 volumio sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:17 volumio sudo[2538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 10:37:17 volumio sudo[2538]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 17 10:37:17 volumio volumio[2446]: info: [1723891037165] CoreMusicLibrary::Adding element 媒体服务器 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 10:37:17 volumio sudo[2535]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 17 10:37:17 volumio volumio[2446]: info: [1723891037195] CoreMusicLibrary::Adding element Last_100 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 17 10:37:17 volumio volumio[2446]: info: [1723891037196] CoreMusicLibrary::Adding element Webradio Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 10:37:17 volumio volumio[2446]: info: Initializing BBC Radios Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: Volumio Calling Home Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::setRepeat null single undefined Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::pushState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioPushState Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::setRandom null Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::pushState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioPushState Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetVisibleSources Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 17 10:37:17 volumio volumio[2446]: info: Received Get System Info Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 17 10:37:17 volumio volumio[2446]: info: Discovery: Getting this device information Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: Listing playlists Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetQueue Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::getQueue Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getQueue Aug 17 10:37:17 volumio volumio[2446]: info: MPD Permissions set Aug 17 10:37:17 volumio volumio[2446]: info: MPD Permissions set Aug 17 10:37:17 volumio volumio[2446]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:37:17 volumio volumio[2446]: info: VolumeController:: Volume=44 Mute =false Aug 17 10:37:17 volumio volumio[2446]: info: CoreStateMachine::pushState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioPushState Aug 17 10:37:17 volumio volumio[2446]: info: Starting Shairport Sync Aug 17 10:37:17 volumio volumio[2446]: info: Starting Shairport Sync Aug 17 10:37:17 volumio sudo[2573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 10:37:17 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:17 volumio sudo[2575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 10:37:17 volumio sudo[2575]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:17 volumio volumio[2446]: info: Starting Shairport Sync Aug 17 10:37:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 10:37:17 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 10:37:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 10:37:17 volumio sudo[2580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 10:37:17 volumio sudo[2580]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 10:37:17 volumio sudo[2575]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:17 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 10:37:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 17 10:37:17 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 17 10:37:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 10:37:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 10:37:17 volumio sudo[2580]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:17 volumio volumio[2446]: info: Shairport-Sync Started Aug 17 10:37:17 volumio volumio[2446]: Error adding Membership: Error: addMembership EINVAL Aug 17 10:37:17 volumio volumio[2446]: info: Shairport-Sync Started Aug 17 10:37:17 volumio volumio[2446]: info: Shairport-Sync Started Aug 17 10:37:17 volumio volumio[2446]: info: Discovery: adding 565a0f03-1723-4b40-baf4-94b77bd16939 Aug 17 10:37:17 volumio volumio[2446]: info: Discovery: Found device Volumio Aug 17 10:37:17 volumio volumio[2446]: info: CoreCommandRouter::volumioGetState Aug 17 10:37:17 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:18 volumio volumio[2446]: info: Volumio called home Aug 17 10:37:20 volumio volumio-remote-updater[656]: [2024-08-17 10:37:20] [connect] Successful connection Aug 17 10:37:20 volumio volumio[2446]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 17 10:37:20 volumio volumio-remote-updater[656]: [2024-08-17 10:37:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723891040 101 Aug 17 10:37:21 volumio volumio[2446]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Aug 17 10:37:22 volumio kernel: usb 1-2: new high-speed USB device number 7 using xhci_hcd Aug 17 10:37:23 volumio kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.11 Aug 17 10:37:23 volumio kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 17 10:37:23 volumio kernel: usb 1-2: Product: USB 2.0 Hub Aug 17 10:37:23 volumio kernel: hub 1-2:1.0: USB hub found Aug 17 10:37:23 volumio kernel: hub 1-2:1.0: 4 ports detected Aug 17 10:37:23 volumio kernel: usb 1-2.2: new low-speed USB device number 8 using xhci_hcd Aug 17 10:37:23 volumio kernel: usb 1-2.2: New USB device found, idVendor=046d, idProduct=c31c, bcdDevice=64.00 Aug 17 10:37:23 volumio kernel: usb 1-2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 17 10:37:23 volumio kernel: usb 1-2.2: Product: USB Keyboard Aug 17 10:37:23 volumio kernel: usb 1-2.2: Manufacturer: Logitech Aug 17 10:37:23 volumio kernel: input: Logitech USB Keyboard as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2/1-2.2:1.0/0003:046D:C31C.0007/input/input19 Aug 17 10:37:23 volumio kernel: hid-generic 0003:046D:C31C.0007: input,hidraw3: USB HID v1.10 Keyboard [Logitech USB Keyboard] on usb-0000:00:14.0-2.2/input0 Aug 17 10:37:23 volumio kernel: input: Logitech USB Keyboard Consumer Control as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2/1-2.2:1.1/0003:046D:C31C.0008/input/input20 Aug 17 10:37:23 volumio kernel: input: Logitech USB Keyboard System Control as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2/1-2.2:1.1/0003:046D:C31C.0008/input/input21 Aug 17 10:37:23 volumio kernel: hid-generic 0003:046D:C31C.0008: input,hidraw4: USB HID v1.10 Device [Logitech USB Keyboard] on usb-0000:00:14.0-2.2/input1 Aug 17 10:37:23 volumio systemd-udevd[2591]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:37:23 volumio systemd-logind[644]: Watching system buttons on /dev/input/event17 (Logitech USB Keyboard System Control) Aug 17 10:37:24 volumio systemd-udevd[2588]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:37:24 volumio systemd-logind[644]: Watching system buttons on /dev/input/event15 (Logitech USB Keyboard) Aug 17 10:37:24 volumio systemd-udevd[2590]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:37:24 volumio kernel: usb 1-2.4: new low-speed USB device number 9 using xhci_hcd Aug 17 10:37:24 volumio kernel: usb 1-2.4: New USB device found, idVendor=1c4f, idProduct=0034, bcdDevice= 1.10 Aug 17 10:37:24 volumio kernel: usb 1-2.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 17 10:37:24 volumio kernel: usb 1-2.4: Product: Usb Mouse Aug 17 10:37:24 volumio kernel: usb 1-2.4: Manufacturer: SIGMACHIP Aug 17 10:37:24 volumio kernel: input: SIGMACHIP Usb Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.4/1-2.4:1.0/0003:1C4F:0034.0009/input/input22 Aug 17 10:37:24 volumio kernel: hid-generic 0003:1C4F:0034.0009: input,hidraw5: USB HID v1.10 Mouse [SIGMACHIP Usb Mouse] on usb-0000:00:14.0-2.4/input0 Aug 17 10:37:24 volumio systemd-udevd[2596]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:37:24 volumio systemd-udevd[2590]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 17 10:37:26 volumio volumio[2446]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Aug 17 10:37:26 volumio sudo[2612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 17 10:37:26 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:26 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:26 volumio sudo[2615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 17 10:37:26 volumio sudo[2615]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:26 volumio sudo[2615]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:26 volumio sudo[2620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 17 10:37:26 volumio sudo[2620]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:26 volumio sudo[2620]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:26 volumio volumio[2446]: info: Upmpdcli Daemon Started Aug 17 10:37:29 volumio volumio[2446]: info: VolumeController::SetAlsaVolume100 Aug 17 10:37:29 volumio volumio[2446]: info: CoreStateMachine::pushState Aug 17 10:37:29 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:29 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 17 10:37:29 volumio volumio[2446]: info: CoreCommandRouter::volumioPushState Aug 17 10:37:31 volumio volumio[2446]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Aug 17 10:37:31 volumio volumio[2446]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/570/21432674-5fa0-4123-90ec-56c4f1641e88.jpg' Aug 17 10:37:31 volumio volumio[2446]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F%3F/b2e8a999-51d0-4873-aa91-8b0b4542db46.jpg' Aug 17 10:37:31 volumio volumio[2446]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bandari/665951db-ffd7-4417-80af-3520d4fecd04.jpg' Aug 17 10:37:31 volumio volumio[2446]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/DJ/a9710a3b-9bb1-400d-9f1a-30d69a7c7f05.jpg' Aug 17 10:37:31 volumio volumio[2446]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ester/c58376f0-6a33-4feb-b059-1b850a06b0d6.jpg' Aug 17 10:37:33 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 10:37:33 volumio volumio[2446]: info: CURURI: artists://%E5%86%B0%E9%9B%A8 Aug 17 10:37:33 volumio volumio[2446]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Aug 17 10:37:36 volumio volumio[2446]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Aug 17 10:37:40 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 10:37:40 volumio volumio[2446]: info: CURURI: artists://%E5%86%B0%E9%9B%A8 Aug 17 10:37:40 volumio volumio[2446]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Aug 17 10:37:44 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 10:37:44 volumio volumio[2446]: info: CURURI: music-library Aug 17 10:37:44 volumio volumio[2446]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Aug 17 10:37:47 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 17 10:37:47 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:47 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 17 10:37:47 volumio volumio[2446]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Aug 17 10:37:47 volumio volumio[2446]: info: ------------------------------------------- Aug 17 10:37:47 volumio volumio[2446]: info: ----- MyVolumio plugins startup ---- Aug 17 10:37:47 volumio volumio[2446]: info: ------------------------------------------- Aug 17 10:37:47 volumio volumio[2446]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 17 10:37:47 volumio volumio[2446]: info: CoreCommandRouter::volumioGetState Aug 17 10:37:47 volumio volumio[2446]: info: CorePlayQueue::getTrack 0 Aug 17 10:37:52 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 10:37:52 volumio volumio[2446]: info: CURURI: music-library Aug 17 10:37:52 volumio volumio[2446]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 17 10:37:58 volumio volumio[2446]: info: Adding plugin bluetooth to MyMusic Plugins Aug 17 10:37:58 volumio volumio[2446]: info: Adding plugin multiroom to MyMusic Plugins Aug 17 10:37:58 volumio volumio[2446]: info: Adding plugin metavolumio to MyMusic Plugins Aug 17 10:37:58 volumio volumio[2446]: info: Adding plugin cd_controller to MyMusic Plugins Aug 17 10:37:58 volumio volumio[2446]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 17 10:37:58 volumio volumio[2446]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 17 10:37:58 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:58 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:37:58 volumio volumio[2446]: info: Starting MyVolumio Remote Streaming Endpoints Aug 17 10:37:58 volumio volumio[2446]: info: MyVolumio not started Aug 17 10:37:58 volumio volumio[2446]: info: Initializing device activation check Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 17 10:37:58 volumio volumio[2446]: info: Starting Streaming Service Transparent Proxy Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 17 10:37:58 volumio volumio[2446]: info: Streaming services startup Aug 17 10:37:58 volumio volumio[2446]: info: Starting Streaming Daemon Aug 17 10:37:58 volumio sudo[2635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 17 10:37:58 volumio sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:37:58 volumio volumio[2446]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 17 10:37:58 volumio sudo[2635]: pam_unix(sudo:session): session closed for user root Aug 17 10:37:58 volumio volumio[2446]: error: Cannot start Volumio Streaming Daemon Aug 17 10:37:58 volumio volumio[2446]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 17 10:37:58 volumio volumio[2446]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 17 10:37:58 volumio volumio[2446]: STREAMING PROXY: Starting server on port 3245 Aug 17 10:37:58 volumio volumio[2446]: Node JS runtime: 14 Aug 17 10:37:59 volumio volumio[2446]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 17 10:37:59 volumio volumio[2446]: info: CURURI: albums:// Aug 17 10:37:59 volumio volumio[2446]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 10:37:59 volumio volumio[2446]: TypeError: Cannot read property 'sendCommand' of undefined Aug 17 10:37:59 volumio volumio[2446]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 Aug 17 10:37:59 volumio volumio[2446]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 17 10:37:59 volumio volumio[2446]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 10:37:59 volumio sudo[2650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 10:36 Aug 17 10:37:59 volumio sudo[2650]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"