-- Logs begin at Tue 2024-10-22 20:27:55 CST, end at Tue 2024-10-22 20:50:56 CST. -- Oct 22 20:49:02 volumio startx[653]: (EE) client bug: timer event6 debounce: offset negative (-0ms) Oct 22 20:49:03 volumio volumio[3758]: info: CoreCommandRouter::volumioGetQueue Oct 22 20:49:03 volumio volumio[3758]: info: CoreStateMachine::getQueue Oct 22 20:49:03 volumio volumio[3758]: info: CorePlayQueue::getQueue Oct 22 20:49:06 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 20:49:06 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 20:49:06 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 20:49:06 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 20:49:06 volumio volumio[3758]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Oct 22 20:49:06 volumio volumio[3758]: info: CoreCommandRouter::volumioGetState Oct 22 20:49:06 volumio volumio[3758]: info: CorePlayQueue::getTrack 0 Oct 22 20:49:07 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 20:49:07 volumio volumio[3758]: info: CURURI: music-library Oct 22 20:49:08 volumio volumio[3758]: info: Preload queue cleared Oct 22 20:49:10 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 20:49:10 volumio volumio[3758]: info: CURURI: music-library/USB Oct 22 20:49:10 volumio volumio[3758]: info: Preload queue cleared Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 20:49:11 volumio volumio[3758]: info: CURURI: music-library/USB/新加卷 Oct 22 20:49:11 volumio volumio[3758]: info: Preload queue cleared Oct 22 20:49:11 volumio volumio[3758]: info: Preloading song: music-library/USB/新加卷/02. 夜宴.dff Oct 22 20:49:11 volumio volumio[3758]: info: Preloading song: music-library/USB/新加卷/07. 走西口.dff Oct 22 20:49:11 volumio volumio[3758]: info: Preloading song: music-library/USB/新加卷/12. 莫斯科郊外的晚上.dff Oct 22 20:49:11 volumio volumio[3758]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 22 20:49:11 volumio volumio[3758]: info: Completed starting MyVolumio Plugin Oct 22 20:49:11 volumio volumio[3758]: info: BOOT COMPLETED Oct 22 20:49:11 volumio volumio[3758]: [Metrics] CommandRouter: 40s 420.27ms Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::volumiosetStartupVolume Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::Close All Modals sent Oct 22 20:49:11 volumio volumio[3758]: info: CoreCommandRouter::Close All Modals sent Oct 22 20:49:11 volumio volumio[3758]: info: Exploding uri music-library/USB/新加卷/02. 夜宴.dff in service mpd Oct 22 20:49:12 volumio volumio[3758]: info: ALBUMART /albumart?cacheid=54&web=//extralarge&path=%2Fmnt%2FUSB%2F%E6%96%B0%E5%8A%A0%E5%8D%B7%2F02.%20%E5%A4%9C%E5%AE%B4.dff&metadata=false Oct 22 20:49:12 volumio volumio[3758]: info: URI /mnt/USB/新加卷/02. 夜宴.dff Oct 22 20:49:12 volumio volumio[3758]: info: Exploding uri music-library/USB/新加卷/07. 走西口.dff in service mpd Oct 22 20:49:12 volumio volumio[3758]: info: Exploding uri music-library/USB/新加卷/12. 莫斯科郊外的晚上.dff in service mpd Oct 22 20:49:12 volumio volumio[3758]: info: ALBUMART /albumart?cacheid=54&web=//extralarge&path=%2Fmnt%2FUSB%2F%E6%96%B0%E5%8A%A0%E5%8D%B7%2F07.%20%E8%B5%B0%E8%A5%BF%E5%8F%A3.dff&metadata=false Oct 22 20:49:12 volumio volumio[3758]: info: URI /mnt/USB/新加卷/07. 走西口.dff Oct 22 20:49:12 volumio volumio[3758]: info: ALBUMART /albumart?cacheid=54&web=//extralarge&path=%2Fmnt%2FUSB%2F%E6%96%B0%E5%8A%A0%E5%8D%B7%2F12.%20%E8%8E%AB%E6%96%AF%E7%A7%91%E9%83%8A%E5%A4%96%E7%9A%84%E6%99%9A%E4%B8%8A.dff&metadata=false Oct 22 20:49:12 volumio volumio[3758]: info: URI /mnt/USB/新加卷/12. 莫斯科郊外的晚上.dff Oct 22 20:49:12 volumio volumio[3758]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 22 20:49:12 volumio volumio[3758]: aplay: main:828: audio open error: Device or resource busy Oct 22 20:49:12 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 22 20:49:12 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 22 20:49:12 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 22 20:49:21 volumio volumio[3758]: info: CoreCommandRouter::volumioGetState Oct 22 20:49:21 volumio volumio[3758]: info: CorePlayQueue::getTrack 0 Oct 22 20:49:34 volumio volumio[3758]: info: Adding mnt/USB/新加卷/SD/G.E.M.邓紫棋 - 后会无期.mp4 to favourites using generic method Oct 22 20:49:34 volumio volumio[3758]: info: Saving local item favourites Oct 22 20:49:34 volumio volumio[3758]: info: Pushing Favourites {"uri":"mnt/USB/新加卷/SD/G.E.M.邓紫棋 - 后会无期.mp4","favourite":true} Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E3%81%8A%E3%81%95%E3%82%80%E3%82%89%E3%81%84%E3%81%95%E3%82%93/ac473795-81ed-4a69-91bd-3808aa1b44ea.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%BE%E4%BA%95%E7%A5%90%E8%B2%B4/4378968d-3a1b-4793-bdde-2bdfa79647db.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E4%BF%8A%E6%9D%B0/32e6203a-d262-4637-aef3-0c07aa164236.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%8F%B2/016f37cb-64ca-4327-9eec-56e5f9a2fefb.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%81%A5/1de797dd-11c6-41e0-a40c-cc1b70a8cdb5.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%96%9B%E4%B9%8B%E8%B0%A6/93dc627f-a3f7-43d2-a0d7-eff65c99c149.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%80%83%E8%B7%91%E8%AE%A1%E5%88%92/ed5e9d2f-8a11-4795-8bb1-f2ea1bd5d3c0.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E9%95%90%E5%93%B2/14ebe909-ffa6-4b98-9e5d-35038fb0a211.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Yolanda%20Adams/Believe/e2dc64ef-f6f2-4e92-8b77-e3505a401e70.png' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/B.B.%20King/Completely%20Well/792832b4-1907-4f71-b95d-868232416ba5.png' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Celine%20Dion/Falling%20Into%20You/f8ab3503-9aa1-4810-846c-3a5dca8f570b.png' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Toots%20Thielemans/The%20Silver%20Collection/412f5c31-3af6-489f-8ac1-58684a8ffe9d.jpg' Oct 22 20:50:07 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Michael%20Jackson/The%20Ultimate%20Collection/dc809fa5-2ab2-4745-bb91-2edca807d3f2.png' Oct 22 20:50:13 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%85%8B%E5%8B%A4/%E5%A4%8F%E6%97%A5%E4%B9%8B%E7%A5%9E%E8%AF%9D/ec92a7a8-1320-4446-89e7-e90360e152a4.jpg' Oct 22 20:50:13 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%96%9B%E4%B9%8B%E8%B0%A6/%E6%84%8F%E5%A4%96/ebc1bc75-7c86-454e-9a43-9d0c480b7c3b.jpg' Oct 22 20:50:13 volumio volumio[3758]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%96%9B%E4%B9%8B%E8%B0%A6/%E7%BB%85%E5%A3%AB/2e3e8003-c8c1-441c-9a28-94634995d227.jpg' Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPlay Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::play index 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::stop Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::stPlaybackTimer Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::updateTrackBlock Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrackBlock Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 0 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::serviceStop Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 0 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::serviceStop Oct 22 20:50:26 volumio volumio[3758]: info: ControllerMpd::stop Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand stop took 339 milliseconds Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::play index undefined Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::startPlaybackTimer Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::clearAddPlayTracks USB/新加卷/SD/泳儿 - 富士山下(Live).mp4 Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 20:50:26 volumio volumio[3758]: info: Oct 22 20:50:26 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:26 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand stop took 17 milliseconds Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 20:50:26 volumio volumio[3758]: info: Oct 22 20:50:26 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:26 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:26 volumio volumio[3758]: info: Oct 22 20:50:26 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:26 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:26 volumio volumio[3758]: info: Oct 22 20:50:26 volumio volumio[3758]: ---------------------------- MPD announces system playlist update Oct 22 20:50:26 volumio volumio[3758]: info: Ignoring MPD Status Update Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand status took 11 milliseconds Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand clear took 5 milliseconds Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand status took 5 milliseconds Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand add "USB/新加卷/SD/泳儿 - 富士山下(Live).mp4" Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:26 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 20:50:26 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::syncState stateService stop Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:26 volumio volumio[3758]: info: No code Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:26 volumio volumio[3758]: info: ------------------------------ 23ms Oct 22 20:50:26 volumio volumio[3758]: info: Oct 22 20:50:26 volumio volumio[3758]: ---------------------------- MPD announces system playlist update Oct 22 20:50:26 volumio volumio[3758]: info: Ignoring MPD Status Update Oct 22 20:50:26 volumio volumio[3758]: info: Oct 22 20:50:26 volumio volumio[3758]: ---------------------------- MPD announces system playlist update Oct 22 20:50:26 volumio volumio[3758]: info: Ignoring MPD Status Update Oct 22 20:50:26 volumio volumio[3758]: error: updateQueue error: null Oct 22 20:50:26 volumio volumio[3758]: info: Oct 22 20:50:26 volumio volumio[3758]: ---------------------------- MPD announces system playlist update Oct 22 20:50:26 volumio volumio[3758]: info: Ignoring MPD Status Update Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand status took 24 milliseconds Oct 22 20:50:26 volumio volumio[3758]: info: ------------------------------ 21ms Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 22 20:50:26 volumio volumio[3758]: info: sendMpdCommand add "USB/新加卷/SD/泳儿 - 富士山下(Live).mp4" took 19 milliseconds Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:50:26 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand play Oct 22 20:50:26 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 22 20:50:26 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::syncState stateService stop Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:26 volumio volumio[3758]: info: No code Oct 22 20:50:26 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:26 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:26 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:26 volumio volumio[3758]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 22 20:50:26 volumio volumio[3758]: info: ------------------------------ 46ms Oct 22 20:50:26 volumio volumio[3758]: info: ------------------------------ 54ms Oct 22 20:50:27 volumio volumio[3758]: info: ------------------------------ 89ms Oct 22 20:50:27 volumio volumio[3758]: info: ------------------------------ 88ms Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand play took 86 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces system playlist update Oct 22 20:50:27 volumio volumio[3758]: info: Ignoring MPD Status Update Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces system playlist update Oct 22 20:50:27 volumio volumio[3758]: info: Ignoring MPD Status Update Oct 22 20:50:27 volumio volumio[3758]: info: ------------------------------ 498ms Oct 22 20:50:27 volumio volumio[3758]: info: ------------------------------ 494ms Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:27 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:27 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:27 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand status took 263 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand status took 264 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand status took 264 milliseconds Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:27 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:27 volumio volumio[3758]: info: Oct 22 20:50:27 volumio volumio[3758]: ---------------------------- MPD announces state update: player Oct 22 20:50:27 volumio volumio[3758]: info: ControllerMpd::getState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand status took 4 milliseconds Oct 22 20:50:27 volumio volumio[3758]: info: sendMpdCommand status took 2 milliseconds Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:50:27 volumio volumio[3758]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:50:28 volumio volumio[3758]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:50:28 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:28 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:50:28 volumio volumio[3758]: verbose: ControllerMpd::parseState Oct 22 20:50:28 volumio volumio[3758]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:50:28 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"泳儿 - 富士山下(Live).mp4","artist":null,"album":null,"uri":"USB/新加卷/SD/泳儿 - 富士山下(Live).mp4","trackType":"mp4"} Oct 22 20:50:28 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState stateService play Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:50:28 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":293,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"泳儿 - 富士山下(Live).mp4","artist":null,"album":null,"uri":"USB/新加卷/SD/泳儿 - 富士山下(Live).mp4","trackType":"mp4"} Oct 22 20:50:28 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState stateService play Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:50:28 volumio volumio[3758]: info: Received an update from plugin. extracting info from payload Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":293,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"泳儿 - 富士山下(Live).mp4","artist":null,"album":null,"uri":"USB/新加卷/SD/泳儿 - 富士山下(Live).mp4","trackType":"mp4"} Oct 22 20:50:28 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState stateService play Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:50:28 volumio volumio[3758]: info: Received an update from plugin. extracting info from payload Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: ------------------------------ 344ms Oct 22 20:50:28 volumio volumio[3758]: info: ------------------------------ 364ms Oct 22 20:50:28 volumio volumio[3758]: info: ------------------------------ 363ms Oct 22 20:50:28 volumio volumio[3758]: info: sendMpdCommand playlistinfo took 93 milliseconds Oct 22 20:50:28 volumio volumio[3758]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 22 20:50:28 volumio volumio[3758]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 22 20:50:28 volumio volumio[3758]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:50:28 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":293,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"泳儿 - 富士山下(Live).mp4","artist":null,"album":null,"uri":"USB/新加卷/SD/泳儿 - 富士山下(Live).mp4","trackType":"mp4"} Oct 22 20:50:28 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState stateService play Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:50:28 volumio volumio[3758]: info: Received an update from plugin. extracting info from payload Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":293,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"泳儿 - 富士山下(Live).mp4","artist":null,"album":null,"uri":"USB/新加卷/SD/泳儿 - 富士山下(Live).mp4","trackType":"mp4"} Oct 22 20:50:28 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState stateService play Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:50:28 volumio volumio[3758]: info: Received an update from plugin. extracting info from payload Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: ControllerMpd::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::servicePushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":293,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"泳儿 - 富士山下(Live).mp4","artist":null,"album":null,"uri":"USB/新加卷/SD/泳儿 - 富士山下(Live).mp4","trackType":"mp4"} Oct 22 20:50:28 volumio volumio[3758]: verbose: CURRENT POSITION 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState stateService play Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:50:28 volumio volumio[3758]: info: Received an update from plugin. extracting info from payload Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: CoreStateMachine::pushState Oct 22 20:50:28 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:28 volumio volumio[3758]: info: CoreCommandRouter::volumioPushState Oct 22 20:50:28 volumio volumio[3758]: info: ------------------------------ 139ms Oct 22 20:50:28 volumio volumio[3758]: info: ------------------------------ 135ms Oct 22 20:50:28 volumio volumio[3758]: info: ------------------------------ 135ms Oct 22 20:50:32 volumio volumio[3758]: info: CoreCommandRouter::volumioGetState Oct 22 20:50:32 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:36 volumio volumio[3758]: info: CoreCommandRouter::volumioGetState Oct 22 20:50:36 volumio volumio[3758]: info: CorePlayQueue::getTrack 16 Oct 22 20:50:56 volumio volumio[3758]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 20:50:56 volumio volumio[3758]: Error: connect ETIMEDOUT 173.234.53.168:80 Oct 22 20:50:56 volumio volumio[3758]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 22 20:50:56 volumio volumio[3758]: errno: -110, Oct 22 20:50:56 volumio volumio[3758]: code: 'ETIMEDOUT', Oct 22 20:50:56 volumio volumio[3758]: syscall: 'connect', Oct 22 20:50:56 volumio volumio[3758]: address: '173.234.53.168', Oct 22 20:50:56 volumio volumio[3758]: port: 80 Oct 22 20:50:56 volumio volumio[3758]: } Oct 22 20:50:56 volumio volumio[3758]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 20:50:56 volumio sudo[4132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 20:49 Oct 22 20:50:56 volumio sudo[4132]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"