-- Logs begin at Sun 2024-05-05 01:41:17 UTC, end at Sun 2024-05-05 01:47:33 UTC. -- May 05 01:46:01 volumio volumio[878]: Upnp client error: Error: This socket has been ended by the other party May 05 01:46:02 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lady%20Gaga/A%20Star%20Is%20Born%20Soundtrack%20(Explicit)/f290b52a-ee78-4357-af09-5fa643c89da6.jpg' May 05 01:46:02 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lady%20Gaga/A%20Star%20Is%20Born%20Soundtrack%20(Without%20Dialogue)/57c201d2-597f-4f12-95c3-a7dba19f6ad3.jpg' May 05 01:46:03 volumio volumio-remote-updater[1942]: [2024-05-05 01:46:03] [disconnect] Disconnect close local:[1000] remote:[1000] May 05 01:46:03 volumio systemd[1]: volumio-remote-updater.service: Succeeded. May 05 01:46:03 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Blue/All%20Rise/40d79fb8-ce56-4122-ad85-299df1501bcc.jpg' May 05 01:46:03 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. May 05 01:46:03 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 3. May 05 01:46:03 volumio systemd[1]: Stopped volumio-remote-updater.service. May 05 01:46:03 volumio systemd[1]: Started volumio-remote-updater.service. May 05 01:46:03 volumio volumio-remote-updater[2119]: Error: No active session May 05 01:46:03 volumio volumio-remote-updater[2119]: [2024-05-05 01:46:03] [connect] Successful connection May 05 01:46:03 volumio volumio-remote-updater[2119]: [2024-05-05 01:46:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714873563 101 May 05 01:46:03 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carolina%20Liar/Coming%20To%20Terms/e1ab5a82-0a4a-4b20-a4e5-03925812550c.jpg' May 05 01:46:04 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Akon/Freedom/119b0545-0b43-4cf5-920a-1fcadbd45a8d.jpg' May 05 01:46:04 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/B2K/Greatest%20Hits/33aaf092-a80d-40e6-9c12-dd7768bde40e.png' May 05 01:46:06 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sarah%20Connor/Key%20To%20My%20Soul/f1fdd900-b91c-4c6c-a038-264ba0b7fa71.png' May 05 01:46:06 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Achim%20Reichel/Melancholie%20und%20Sturmflut/049c0c8b-1b30-4e1c-99ea-c61e9ef134ff.jpg' May 05 01:46:06 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Nelly/Nellyville/a8641d5e-8bf0-47ff-8ac6-00b92e923f64.jpg' May 05 01:46:07 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Reik/Reik/846c2d6f-e044-4f94-abc9-483a59b91423.jpg' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lady%20Gaga/Shallow/bf0e74a6-2651-420f-a1a2-c88b6e119298.jpg' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/She%20Wants%20Revenge/She%20Wants%20Revenge/5b0a328a-d87b-476b-bbb6-5d41f4ddc09d.png' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jeff%20Hanson/Son/e5fe2ad2-8743-48d3-8106-f01a702a98a1.jpg' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alexandro%20Quereval%C3%BA/The%20Best%20of%20Alexandro%20I/461ed596-d01b-48b3-9073-2dda4c46adaa.jpg' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/LOVE%20PSYCHEDELICO/THE%20GREATEST%20HITS/dd4c1209-0a20-45b6-ba75-2d852cb73965.png' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fort%20Minor/The%20Rising%20Tied/127aa66f-8d12-423c-8312-217fc1e9d4d3.png' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/sheryfa%20luna/venus/bd51a772-c8c8-4d55-8e2b-2a5f1505dec5.jpg' May 05 01:46:08 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hans%20Zimmer/World%20of%20Walker/d3a76f68-280f-4e80-9a3d-51371761fb4f.jpg' May 05 01:46:09 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%B7%E6%9D%A5%E9%98%BF%E6%9C%A8/%E4%B8%8D%E8%BF%87%E4%BA%BA%E9%97%B4/8e94e019-f6e5-4440-b01a-796f6a2021ae.jpg' May 05 01:46:09 volumio volumio[878]: 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/%E4%B8%96%E7%95%8C/7b2dcb6b-5dd4-4d75-b620-142ec557c2c8.jpg' May 05 01:46:10 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%AA%E5%B3%B0/%E4%BF%A1%E4%BB%B0%E5%9C%A8%E7%A9%BA%E4%B8%AD%E9%A3%98%E6%89%AC/5c7051c8-698b-4df5-9444-dd33c8778039.jpg' May 05 01:46:10 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%88%80%E9%83%8E%E2%85%A2/b53304f5-2e48-4601-bab2-2f00542d0092.jpg' May 05 01:46:10 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B0%A2%E5%A4%A9%E7%AC%91/%E5%8F%A4%E7%AD%9D%E9%9B%B7%E9%AC%BC/d52fb21a-6560-449a-b993-e9a6144583aa.jpg' May 05 01:46:11 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B7%8D/%E5%9C%A8%E8%B7%AF%E4%B8%8A%E2%80%A6%E2%80%A6/74f546bb-e32f-4717-92ec-4cf5b85ae7c5.png' May 05 01:46:12 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%AE%97%E7%9B%9B/%E5%B1%B1%E4%B8%98/0e16f977-5765-4db8-aa28-b8526a99f542.png' May 05 01:46:13 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9A%94%E5%A3%81%E8%80%81%E6%A8%8A/%E6%88%91%E6%9B%BE/8437707f-6c3c-4d4f-aee4-231ef464f402.jpg' May 05 01:46:13 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B7%8D/%E6%9B%BE%E7%BB%8F%E7%9A%84%E4%BD%A0/0793426d-8ec3-404e-9afe-276de9895161.jpg' May 05 01:46:15 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B7%8D/%E6%AF%8F%E4%B8%80%E5%88%BB%E9%83%BD%E6%98%AF%E5%B4%AD%E6%96%B0%E7%9A%84/34e3189d-ddbc-4460-b152-db456bd7ef4b.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%AA%E5%B3%B0/%E7%94%9F%E6%97%A0%E6%89%80%E6%B1%82/45678381-9b06-4155-ae5d-baeb650f3a3f.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%AA%E5%B3%B0/%E7%94%9F%E6%9D%A5%E5%BD%B7%E5%BE%A8/bfee38ba-a5dc-4f4f-9bd0-b36652bb9f62.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%AA%E5%B3%B0/%E7%AC%91%E7%9D%80%E5%93%AD/c242746a-2838-4d6f-8e7a-7d615c5aefd7.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B7%8D/%E7%AC%AC%E4%B8%89%E6%9E%81/d66f9030-5da2-4eb5-ac20-e044bc14fe2b.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B4%AA%E5%90%AF/%E7%BA%A2%E9%9B%AA%E8%8E%B2/8f5b6894-3d77-43c6-b905-b7a64c69b4a3.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AD%99%E6%A5%A0/%E7%BC%98%E5%88%86%E7%9A%84%E5%A4%A9%E7%A9%BA/37dfe00e-185e-4f32-a2f5-05a634137586.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BA%91%E6%9C%B5/%E7%BC%98%E5%AE%9A%E4%BB%8A%E7%94%9F/2eff837c-cf62-4a90-aba0-00f02df6ca4c.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%AA%E5%B3%B0/%E8%8A%B1%E7%81%AB/114557c5-c1c6-4c15-be96-82df9ea23db7.jpg' May 05 01:46:16 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8B%8F%E9%98%B3/%E8%B4%A4%E8%89%AF/0e6cd3ec-2543-489c-829b-0ff47805757e.jpg' May 05 01:46:19 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B4%94%E5%81%A5/%E8%A7%A3%E6%B1%BA/cef423f7-8402-4286-b74e-2b398ff02f23.jpg' May 05 01:46:19 volumio volumio[878]: info: CoreCommandRouter::volumioAddQueueItems May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::addQueueItems May 05 01:46:19 volumio volumio[878]: info: CorePlayQueue::addQueueItems May 05 01:46:19 volumio volumio[878]: info: Adding Item to queue: albums://Mayumi%20Itsuwa/Good%20Time%20Collectible%20Series May 05 01:46:19 volumio volumio[878]: info: Exploding uri albums://Mayumi%20Itsuwa/Good%20Time%20Collectible%20Series in service mpd May 05 01:46:19 volumio volumio[878]: info: CorePlayQueue::saveQueue May 05 01:46:19 volumio volumio[878]: info: CoreCommandRouter::volumioPushQueue May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::updateTrackBlock May 05 01:46:19 volumio volumio[878]: info: CorePlayQueue::getTrackBlock May 05 01:46:19 volumio volumio[878]: info: CoreCommandRouter::volumioPlay May 05 01:46:19 volumio volumio[878]: UNSET VOLATILE May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::play index 22 May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::stop May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:46:19 volumio volumio[878]: UNSET VOLATILE May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::stPlaybackTimer May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::updateTrackBlock May 05 01:46:19 volumio volumio[878]: info: CorePlayQueue::getTrackBlock May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::pushState May 05 01:46:19 volumio volumio[878]: info: CorePlayQueue::getTrack 20 May 05 01:46:19 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 05 01:46:19 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:19 volumio volumio[878]: info: CoreStateMachine::serviceStop May 05 01:46:19 volumio volumio[878]: info: CorePlayQueue::getTrack 20 May 05 01:46:19 volumio volumio[878]: info: CoreCommandRouter::serviceStop May 05 01:46:19 volumio volumio[878]: info: ControllerMpd::stop May 05 01:46:19 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand stop May 05 01:46:20 volumio volumio[878]: info: May 05 01:46:20 volumio volumio[878]: ---------------------------- MPD announces state update: player May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand stop took 243 milliseconds May 05 01:46:20 volumio volumio[878]: info: ControllerMpd::getState May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::play index undefined May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::startPlaybackTimer May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/#recycle/sacd/sacd/五轮真弓[SONY好时代珍藏系列回忆录 SACD/01 - Mayumi Itsuwa - Hitohabune (Album Version).dsf May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand stop May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand status took 2 milliseconds May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand stop took 1 milliseconds May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::parseState May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand clear May 05 01:46:20 volumio volumio[878]: info: May 05 01:46:20 volumio volumio[878]: ---------------------------- MPD announces system playlist update May 05 01:46:20 volumio volumio[878]: info: Ignoring MPD Status Update May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand clear took 1 milliseconds May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/#recycle/sacd/sacd/五轮真弓[SONY好时代珍藏系列回忆录 SACD/01 - Mayumi Itsuwa - Hitohabune (Album Version).dsf" May 05 01:46:20 volumio volumio[878]: info: ControllerMpd::pushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::pushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.ape","artist":null,"album":null,"uri":"NAS/music/cd/~~~彭丽媛《晶致－金色歌唱家》/CD1/CDImage.ape","trackType":"ape"} May 05 01:46:20 volumio volumio[878]: verbose: CURRENT POSITION 22 May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::syncState stateService stop May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus stop May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::pushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:20 volumio volumio[878]: info: No code May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::pushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:20 volumio volumio[878]: info: ------------------------------ 10ms May 05 01:46:20 volumio volumio[878]: error: updateQueue error: null May 05 01:46:20 volumio volumio[878]: info: May 05 01:46:20 volumio volumio[878]: ---------------------------- MPD announces system playlist update May 05 01:46:20 volumio volumio[878]: info: Ignoring MPD Status Update May 05 01:46:20 volumio volumio[878]: info: ------------------------------ 7ms May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand add "NAS/music/#recycle/sacd/sacd/五轮真弓[SONY好时代珍藏系列回忆录 SACD/01 - Mayumi Itsuwa - Hitohabune (Album Version).dsf" took 6 milliseconds May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand play May 05 01:46:20 volumio volumio[878]: info: ------------------------------ 1ms May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand play took 2 milliseconds May 05 01:46:20 volumio volumio[878]: info: May 05 01:46:20 volumio volumio[878]: ---------------------------- MPD announces state update: player May 05 01:46:20 volumio volumio[878]: info: ControllerMpd::getState May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand status took 2 milliseconds May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::parseState May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:46:20 volumio volumio[878]: info: May 05 01:46:20 volumio volumio[878]: ---------------------------- MPD announces state update: player May 05 01:46:20 volumio volumio[878]: info: ControllerMpd::getState May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 05 01:46:20 volumio volumio[878]: info: ControllerMpd::pushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hitohabune (Album Version)","artist":"Mayumi Itsuwa","album":"Good Time Collectible Series","uri":"NAS/music/#recycle/sacd/sacd/五轮真弓[SONY好时代珍藏系列回忆录 SACD/01 - Mayumi Itsuwa - Hitohabune (Album Version).dsf","trackType":"dsf"} May 05 01:46:20 volumio volumio[878]: verbose: CURRENT POSITION 22 May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::syncState stateService play May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus stop May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::pushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:20 volumio volumio[878]: info: ------------------------------ 6ms May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand status took 2 milliseconds May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::parseState May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:46:20 volumio volumio[878]: info: sendMpdCommand playlistinfo took 5 milliseconds May 05 01:46:20 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 05 01:46:20 volumio volumio[878]: info: ControllerMpd::pushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hitohabune (Album Version)","artist":"Mayumi Itsuwa","album":"Good Time Collectible Series","uri":"NAS/music/#recycle/sacd/sacd/五轮真弓[SONY好时代珍藏系列回忆录 SACD/01 - Mayumi Itsuwa - Hitohabune (Album Version).dsf","trackType":"dsf"} May 05 01:46:20 volumio volumio[878]: verbose: CURRENT POSITION 22 May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::syncState stateService play May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus play May 05 01:46:20 volumio volumio[878]: info: Received an update from plugin. extracting info from payload May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::pushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:20 volumio volumio[878]: info: CoreStateMachine::pushState May 05 01:46:20 volumio volumio[878]: info: CorePlayQueue::getTrack 22 May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 05 01:46:20 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:20 volumio volumio[878]: info: ------------------------------ 11ms May 05 01:46:20 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B8%8D%E4%B8%80%E5%AE%9A/%E5%85%AB%E5%92%8C/283e97e3-c898-483d-b58f-e0e090ba66ad.jpg' May 05 01:46:21 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AA%A6%E5%94%AF/%E5%85%AB%E6%AE%B5%E9%94%A6/9cb2d9fe-b340-4277-bc70-6c548a648a23.jpg' May 05 01:46:21 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AA%A6%E5%94%AF/%E5%B1%B1%E6%B2%B3%E6%B0%B4/8d6e2737-cda0-4dec-b291-05049f1d63fc.jpg' May 05 01:46:21 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AA%A6%E5%94%AF/%E6%9A%AE%E8%89%AF%E6%96%87%E7%8E%8B/4c411653-cb1b-492f-895b-202ac573daed.jpg' May 05 01:46:21 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AA%A6%E5%94%AF/%E8%89%B3%E9%98%B3%E5%A4%A9/d7495f76-3ecd-4873-98b2-dfa553ded28b.jpg' May 05 01:46:21 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AA%A6%E5%94%AF%C2%B7FM3/%E9%95%9C%E8%8A%B1%E7%BC%98%E8%AE%B0/2d85116d-f293-441f-af55-4aca8d1daeea.jpg' May 05 01:46:21 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AA%A6%E5%94%AF%C2%B7%E8%AF%91/%E5%8F%A3%E9%9F%B3/15a0d8dd-45fe-43cf-81da-05420a2ec723.jpg' May 05 01:46:24 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%BC%E6%96%AF%E6%A5%9E/%E5%A1%9E%E5%8C%97/caf4e8c7-261e-4fb3-9b69-5b9bfdce25d2.jpg' May 05 01:46:26 volumio volumio[878]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/Furious%207%3A%20Original%20Motion%20Picture%20Soundtrack/f94df1f9-fa32-46c0-8e25-155a5cbe6c57.png' May 05 01:46:28 volumio volumio[878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:46:28 volumio volumio[878]: Error: socket hang up May 05 01:46:28 volumio volumio[878]: at connResetException (internal/errors.js:639:14) May 05 01:46:28 volumio volumio[878]: at TLSSocket.socketOnEnd (_http_client.js:499:23) May 05 01:46:28 volumio volumio[878]: at TLSSocket.emit (events.js:412:35) May 05 01:46:28 volumio volumio[878]: at endReadableNT (internal/streams/readable.js:1334:12) May 05 01:46:28 volumio volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { May 05 01:46:28 volumio volumio[878]: code: 'ECONNRESET' May 05 01:46:28 volumio volumio[878]: } May 05 01:46:28 volumio volumio[878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:46:28 volumio sudo[2179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-05 01:45 May 05 01:46:28 volumio sudo[2179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:28 volumio sudo[2179]: pam_unix(sudo:session): session closed for user root May 05 01:46:30 volumio volumio-remote-updater[2119]: [2024-05-05 01:46:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 05 01:46:30 volumio volumio-remote-updater[2119]: [2024-05-05 01:46:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 05 01:46:30 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 05 01:46:30 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 05 01:46:30 volumio systemd[1]: Started dynamicswap service. May 05 01:46:30 volumio systemd[1]: dynamicswap.service: Succeeded. May 05 01:46:30 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 05 01:46:30 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 05 01:46:30 volumio systemd[1]: Stopped Volumio Backend Module. May 05 01:46:30 volumio systemd[1]: Started Volumio Backend Module. May 05 01:46:30 volumio systemd[1]: Started dynamicswap service. May 05 01:46:30 volumio systemd[1]: dynamicswap.service: Succeeded. May 05 01:46:31 volumio volumio[2193]: info: ------------------------------------------- May 05 01:46:31 volumio volumio[2193]: info: ----- Volumio2 ---- May 05 01:46:31 volumio volumio[2193]: info: ------------------------------------------- May 05 01:46:31 volumio volumio[2193]: info: ----- System startup ---- May 05 01:46:31 volumio volumio[2193]: info: ------------------------------------------- May 05 01:46:31 volumio volumio[2193]: info: MYVOLUMIO Environment detected May 05 01:46:31 volumio volumio[2193]: info: Plugin folders cleanup May 05 01:46:31 volumio volumio[2193]: info: Scanning into folder /volumio/app/plugins/ May 05 01:46:31 volumio volumio[2193]: info: Scanning category audio_interface May 05 01:46:31 volumio volumio[2193]: info: Scanning category miscellanea May 05 01:46:31 volumio volumio[2193]: info: Scanning category music_service May 05 01:46:31 volumio volumio[2193]: info: Scanning category plugins.json May 05 01:46:31 volumio volumio[2193]: info: Scanning category system_controller May 05 01:46:31 volumio volumio[2193]: info: Scanning category user_interface May 05 01:46:31 volumio volumio[2193]: info: Scanning into folder /data/plugins/ May 05 01:46:31 volumio volumio[2193]: info: Plugin folders cleanup completed May 05 01:46:31 volumio volumio[2193]: info: ------------------------------------------- May 05 01:46:31 volumio volumio[2193]: info: ----- Core plugins startup ---- May 05 01:46:31 volumio volumio[2193]: info: ------------------------------------------- May 05 01:46:31 volumio volumio[2193]: info: Loading plugins from folder /volumio/app/plugins/ May 05 01:46:31 volumio volumio[2193]: info: Adding plugin upnp to MyMusic Plugins May 05 01:46:31 volumio volumio[2193]: info: Adding plugin airplay_emulation to MyMusic Plugins May 05 01:46:31 volumio volumio[2193]: info: Adding plugin upnp_browser to MyMusic Plugins May 05 01:46:31 volumio volumio[2193]: info: Loading plugins from folder /data/plugins/ May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "system"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "appearance"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "network"... May 05 01:46:31 volumio volumio[2193]: info: Refreshing Cached IP Addresses May 05 01:46:31 volumio sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:46:31 volumio sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:31 volumio sudo[2218]: pam_unix(sudo:session): session closed for user root May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "services"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "alsa_controller"... May 05 01:46:31 volumio sudo[2221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:46:31 volumio sudo[2221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:31 volumio sudo[2221]: pam_unix(sudo:session): session closed for user root May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "wizard"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "volumio_command_line_client"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "upnp"... May 05 01:46:31 volumio volumio[2193]: info: [1714873591415] Starting Upmpd Daemon May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "my_music"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "mpd"... May 05 01:46:31 volumio volumio[2193]: info: Creating MPD Configuration file May 05 01:46:31 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 01:46:31 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:31 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "upnp_browser"... May 05 01:46:31 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 01:46:31 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:31 volumio systemd[1]: Stopping Music Player Daemon... May 05 01:46:31 volumio systemd[1]: mpd.service: Succeeded. May 05 01:46:31 volumio systemd[1]: Stopped Music Player Daemon. May 05 01:46:31 volumio systemd[1]: Starting Music Player Daemon... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "networkfs"... May 05 01:46:31 volumio volumio[2193]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds May 05 01:46:31 volumio volumio[2193]: info: Starting Udev Watcher for removable devices May 05 01:46:31 volumio volumio[2193]: info: Ignoring mount for partition: boot May 05 01:46:31 volumio volumio[2193]: info: Ignoring mount for partition: volumio May 05 01:46:31 volumio volumio[2193]: info: Ignoring mount for partition: volumio_data May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "alarm-clock"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "airplay_emulation"... May 05 01:46:31 volumio volumio[2193]: info: Starting Shairport Sync May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "last_100"... May 05 01:46:31 volumio mpd[2236]: May 05 01:46 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "webradio"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "i2s_dacs"... May 05 01:46:31 volumio volumio[2193]: info: I2S DAC not set, start Auto-detection May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "volumiodiscovery"... May 05 01:46:31 volumio volumio[2193]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 01:46:31 volumio volumio[2193]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:46:31 volumio volumio[2193]: *** WARNING *** For more information see May 05 01:46:31 volumio volumio[2193]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 01:46:31 volumio volumio[2193]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:46:31 volumio volumio[2193]: *** WARNING *** For more information see May 05 01:46:31 volumio node[2193]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 01:46:31 volumio node[2193]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:46:31 volumio node[2193]: *** WARNING *** For more information see May 05 01:46:31 volumio node[2193]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 01:46:31 volumio node[2193]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:46:31 volumio node[2193]: *** WARNING *** For more information see May 05 01:46:31 volumio volumio[2193]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 01:46:31 volumio volumio[2193]: info: Discovery: Started advertising with name: Volumio May 05 01:46:31 volumio mpd[2236]: May 05 01:46 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 05 01:46:31 volumio mpd[2236]: May 05 01:46 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 05 01:46:31 volumio systemd[1]: Started Music Player Daemon. May 05 01:46:31 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "outputs"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "albumart"... May 05 01:46:31 volumio volumio[2193]: info: Plugin example_plugin is not enabled May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "inputs"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "updater_comm"... May 05 01:46:31 volumio volumio[2193]: info: Plugin mpdemulation is not enabled May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "rest_api"... May 05 01:46:31 volumio volumio[2193]: info: Loading plugin "websocket"... May 05 01:46:31 volumio volumio[2193]: info: Loading i18n strings for locale zh May 05 01:46:31 volumio volumio[2193]: Updating browse sources language May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::initPlayerControls May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: Forking 3 albumart workers May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:46:31 volumio volumio[2193]: Express server listening on port 3000 May 05 01:46:31 volumio volumio[2193]: [Metrics] WebUI: 0s 672.34ms May 05 01:46:31 volumio volumio[2193]: info: Setting Device type: x86 May 05 01:46:31 volumio volumio[2193]: Volumio Calling Home May 05 01:46:31 volumio volumio[2193]: info: CoreStateMachine::resetVolumioState May 05 01:46:31 volumio volumio[2193]: info: CoreStateMachine::getcurrentVolume May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::volumioRetrievevolume May 05 01:46:31 volumio volumio[2193]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 05 01:46:31 volumio volumio[2193]: info: Completed loading Core Plugins May 05 01:46:31 volumio volumio[2193]: info: Preparing to generate the ALSA configuration file May 05 01:46:31 volumio volumio[2193]: info: MPD Permissions set May 05 01:46:31 volumio sudo[2278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 05 01:46:31 volumio sudo[2278]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:31 volumio volumio[2193]: Starting albumart workers May 05 01:46:31 volumio volumio[2193]: Starting albumart workers May 05 01:46:31 volumio volumio[2193]: Starting albumart workers May 05 01:46:31 volumio volumio[2193]: info: MPD running with PID2236 May 05 01:46:31 volumio volumio[2193]: ,establishing connection May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:31 volumio volumio[2193]: info: Reloading queue from file May 05 01:46:31 volumio volumio[2193]: error: updateQueue error: null May 05 01:46:31 volumio volumio[2193]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 05 01:46:31 volumio volumio[2193]: info: VolumeController:: Volume=100 Mute =false May 05 01:46:31 volumio volumio[2193]: info: CoreStateMachine::pushState May 05 01:46:31 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::volumioPushState May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:31 volumio volumio[2193]: info: CoreStateMachine::updateTrackBlock May 05 01:46:31 volumio volumio[2193]: info: CorePlayQueue::getTrackBlock May 05 01:46:31 volumio volumio[2193]: info: CoreCommandRouter::volumioRetrievevolume May 05 01:46:31 volumio volumio[2193]: info: Asound.conf file unchanged, so no further update is needed May 05 01:46:31 volumio volumio[2193]: info: Output device has changed, restarting MPD May 05 01:46:32 volumio volumio[2193]: info: Output device has changed, restarting Shairport Sync May 05 01:46:32 volumio sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 01:46:32 volumio sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:32 volumio sudo[2319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 01:46:32 volumio sudo[2319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:32 volumio systemd[1]: Stopping Music Player Daemon... May 05 01:46:32 volumio sudo[2319]: pam_unix(sudo:session): session closed for user root May 05 01:46:32 volumio systemd[1]: mpd.service: Succeeded. May 05 01:46:32 volumio systemd[1]: Stopped Music Player Daemon. May 05 01:46:32 volumio systemd[1]: Starting Music Player Daemon... May 05 01:46:32 volumio volumio[2193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:46:32 volumio volumio[2193]: info: ___________ START PLUGINS ___________ May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:46:32 volumio volumio[2193]: info: [1714873592024] CoreMusicLibrary::Adding element Media Servers May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:32 volumio volumio[2193]: Cannot find translation for sourceMedia Servers May 05 01:46:32 volumio volumio[2193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:46:32 volumio volumio[2193]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:46:32 volumio volumio[2193]: info: [1714873592051] CoreMusicLibrary::Adding element Last_100 May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:32 volumio volumio[2193]: Cannot find translation for sourceMedia Servers May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:46:32 volumio volumio[2193]: info: [1714873592051] CoreMusicLibrary::Adding element Webradio May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:32 volumio volumio[2193]: Cannot find translation for sourceMedia Servers May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:46:32 volumio volumio[2193]: info: Completed starting Core Plugins May 05 01:46:32 volumio volumio[2193]: info: ------------------------------------------- May 05 01:46:32 volumio volumio[2193]: info: ----- MyVolumio plugins startup ---- May 05 01:46:32 volumio volumio[2193]: info: ------------------------------------------- May 05 01:46:32 volumio volumio[2193]: info: [MyVolumio PluginManager] Fetching plans data.... May 05 01:46:32 volumio volumio[2193]: info: CoreStateMachine::setRepeat false single undefined May 05 01:46:32 volumio volumio[2193]: info: CoreStateMachine::pushState May 05 01:46:32 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioPushState May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:32 volumio volumio[2193]: info: CoreStateMachine::setRandom true May 05 01:46:32 volumio volumio[2193]: info: CoreStateMachine::pushState May 05 01:46:32 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioPushState May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:32 volumio volumio[2193]: error: MPD error: Error: write EPIPE May 05 01:46:32 volumio volumio[2193]: info: mDNS: A device disapperared from network May 05 01:46:32 volumio volumio[2193]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined May 05 01:46:32 volumio volumio[2193]: info: MPD Permissions set May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioGetState May 05 01:46:32 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:32 volumio volumio[2193]: info: VolumeController:: Volume=100 Mute =false May 05 01:46:32 volumio volumio[2193]: info: CoreStateMachine::pushState May 05 01:46:32 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioPushState May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:32 volumio volumio[2193]: info: Listing playlists May 05 01:46:32 volumio volumio[2193]: info: Starting Shairport Sync May 05 01:46:32 volumio volumio[2193]: info: Starting Shairport Sync May 05 01:46:32 volumio sudo[2344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:46:32 volumio sudo[2344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:32 volumio volumio[2193]: info: Starting Shairport Sync May 05 01:46:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:46:32 volumio sudo[2347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:46:32 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 01:46:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:46:32 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:46:32 volumio sudo[2344]: pam_unix(sudo:session): session closed for user root May 05 01:46:32 volumio sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:46:32 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:46:32 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 01:46:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:46:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:46:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:46:32 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 05 01:46:32 volumio systemd[1]: shairport-sync.service: Succeeded. May 05 01:46:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:46:32 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root May 05 01:46:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:46:32 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root May 05 01:46:32 volumio volumio[2193]: info: Shairport-Sync Started May 05 01:46:32 volumio volumio[2193]: Error adding Membership: Error: addMembership EINVAL May 05 01:46:32 volumio volumio[2193]: info: Shairport-Sync Started May 05 01:46:32 volumio volumio[2193]: info: Shairport-Sync Started May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioGetState May 05 01:46:32 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:32 volumio mpd[2335]: May 05 01:46 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 01:46:32 volumio mpd[2335]: May 05 01:46 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 05 01:46:32 volumio mpd[2335]: May 05 01:46 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 05 01:46:32 volumio systemd[1]: Started Music Player Daemon. May 05 01:46:32 volumio sudo[2321]: pam_unix(sudo:session): session closed for user root May 05 01:46:32 volumio volumio[2193]: error: updateQueue error: null May 05 01:46:32 volumio volumio[2193]: info: Discovery: adding 0ac9bffe-1536-4f5b-8c15-b8621ab68f70 May 05 01:46:32 volumio volumio[2193]: info: mDNS: Found device Volumio May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioGetState May 05 01:46:32 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:32 volumio volumio[2193]: info: Discovery: this is already registered, 0ac9bffe-1536-4f5b-8c15-b8621ab68f70 May 05 01:46:32 volumio volumio[2193]: info: mDNS: Found device Volumio May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::volumioGetState May 05 01:46:32 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:32 volumio volumio[2193]: info: Volumio called home May 05 01:46:32 volumio sudo[2278]: pam_unix(sudo:session): session closed for user root May 05 01:46:32 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetState May 05 01:46:33 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:33 volumio volumio[2193]: info: Listing playlists May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetQueue May 05 01:46:33 volumio volumio[2193]: info: CoreStateMachine::getQueue May 05 01:46:33 volumio volumio[2193]: info: CorePlayQueue::getQueue May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetState May 05 01:46:33 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:33 volumio volumio[2193]: info: Listing playlists May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetQueue May 05 01:46:33 volumio volumio[2193]: info: CoreStateMachine::getQueue May 05 01:46:33 volumio volumio[2193]: info: CorePlayQueue::getQueue May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetState May 05 01:46:33 volumio volumio[2193]: info: CorePlayQueue::getTrack 0 May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:46:33 volumio volumio[2193]: info: Listing playlists May 05 01:46:33 volumio volumio[2193]: info: CoreCommandRouter::volumioGetQueue May 05 01:46:33 volumio volumio[2193]: info: CoreStateMachine::getQueue May 05 01:46:33 volumio volumio[2193]: info: CorePlayQueue::getQueue May 05 01:46:33 volumio volumio[2193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Adele/19/0a421a40-c47a-4475-8eac-67f130233e4e.jpg' May 05 01:46:35 volumio volumio[2193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B0%AD%E5%92%8F%E9%BA%9F/%E7%88%B1%E6%83%85%E9%99%B7%E9%98%B1/009d3b38-d3ea-4292-9277-a35bd43699a7.jpg' May 05 01:46:35 volumio volumio[2193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E5%A5%95%E8%BF%85/Time%20Flies/69a1cbd5-0b30-4be8-bae4-634b64ef740c.jpg' May 05 01:46:35 volumio volumio[2193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beyond/%E7%8A%B9%E8%B1%AB/c50adb52-112d-4486-9973-b85d6287aeed.jpg' May 05 01:46:35 volumio volumio[2193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E6%9F%8F%E8%B1%AA/8/d274e321-b254-413f-8bbe-a7ace3be20ae.png' May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::volumioAddQueueItems May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::addQueueItems May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::addQueueItems May 05 01:46:35 volumio volumio[2193]: info: Adding Item to queue: albums:///%E6%9C%AA%E7%9F%A5%E6%A0%87%E9%A2%98 May 05 01:46:35 volumio volumio[2193]: info: Exploding uri albums:///%E6%9C%AA%E7%9F%A5%E6%A0%87%E9%A2%98 in service mpd May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::saveQueue May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::volumioPushQueue May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::updateTrackBlock May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrackBlock May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::volumioPlay May 05 01:46:35 volumio volumio[2193]: UNSET VOLATILE May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::play index 58 May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::stop May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:46:35 volumio volumio[2193]: UNSET VOLATILE May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::play index undefined May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrack 58 May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::startPlaybackTimer May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrack 58 May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/#recycle/cd/柏菲唱片/~~~窦鹏《深情难了》/CDImage.ape May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand stop May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand stop took 0 milliseconds May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand clear May 05 01:46:35 volumio volumio[2193]: info: May 05 01:46:35 volumio volumio[2193]: ---------------------------- MPD announces system playlist update May 05 01:46:35 volumio volumio[2193]: info: Ignoring MPD Status Update May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand clear took 0 milliseconds May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/#recycle/cd/柏菲唱片/~~~窦鹏《深情难了》/CDImage.ape" May 05 01:46:35 volumio volumio[2193]: error: updateQueue error: null May 05 01:46:35 volumio volumio[2193]: info: May 05 01:46:35 volumio volumio[2193]: ---------------------------- MPD announces system playlist update May 05 01:46:35 volumio volumio[2193]: info: Ignoring MPD Status Update May 05 01:46:35 volumio volumio[2193]: info: ------------------------------ 2ms May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand add "NAS/music/#recycle/cd/柏菲唱片/~~~窦鹏《深情难了》/CDImage.ape" took 1 milliseconds May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand play May 05 01:46:35 volumio volumio[2193]: info: ------------------------------ 1ms May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand play took 1 milliseconds May 05 01:46:35 volumio volumio[2193]: info: May 05 01:46:35 volumio volumio[2193]: ---------------------------- MPD announces state update: player May 05 01:46:35 volumio volumio[2193]: info: ControllerMpd::getState May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand status May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand status took 5 milliseconds May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::parseState May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:46:35 volumio volumio[2193]: info: May 05 01:46:35 volumio volumio[2193]: ---------------------------- MPD announces state update: player May 05 01:46:35 volumio volumio[2193]: info: ControllerMpd::getState May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand status May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::parseTrackInfo May 05 01:46:35 volumio volumio[2193]: info: ControllerMpd::pushState May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::servicePushState May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrack 58 May 05 01:46:35 volumio volumio[2193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3086,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"G:\\窦鹏《深情难了》\\CDImage","artist":null,"album":"未知标题","uri":"NAS/music/#recycle/cd/柏菲唱片/~~~窦鹏《深情难了》/CDImage.ape","trackType":"ape"} May 05 01:46:35 volumio volumio[2193]: verbose: CURRENT POSITION 58 May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::syncState stateService play May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::syncState currentStatus stop May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::pushState May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrack 58 May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::volumioPushState May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:35 volumio volumio[2193]: info: ------------------------------ 9ms May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand status took 4 milliseconds May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::parseState May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 01:46:35 volumio volumio[2193]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 01:46:35 volumio volumio[2193]: verbose: ControllerMpd::parseTrackInfo May 05 01:46:35 volumio volumio[2193]: info: ControllerMpd::pushState May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::servicePushState May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrack 58 May 05 01:46:35 volumio volumio[2193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":3086,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"G:\\窦鹏《深情难了》\\CDImage","artist":null,"album":"未知标题","uri":"NAS/music/#recycle/cd/柏菲唱片/~~~窦鹏《深情难了》/CDImage.ape","trackType":"ape"} May 05 01:46:35 volumio volumio[2193]: verbose: CURRENT POSITION 58 May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::syncState stateService play May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::syncState currentStatus play May 05 01:46:35 volumio volumio[2193]: info: Received an update from plugin. extracting info from payload May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::pushState May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrack 58 May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::volumioPushState May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:35 volumio volumio[2193]: info: CoreStateMachine::pushState May 05 01:46:35 volumio volumio[2193]: info: CorePlayQueue::getTrack 58 May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::volumioPushState May 05 01:46:35 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:46:35 volumio volumio[2193]: info: ------------------------------ 8ms May 05 01:46:35 volumio volumio-remote-updater[2119]: [2024-05-05 01:46:35] [connect] Successful connection May 05 01:46:36 volumio volumio-remote-updater[2119]: [2024-05-05 01:46:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714873595 101 May 05 01:46:36 volumio volumio[2193]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 05 01:46:40 volumio volumio[2193]: info: Adding plugin bluetooth to MyMusic Plugins May 05 01:46:40 volumio volumio[2193]: info: Adding plugin cd_controller to MyMusic Plugins May 05 01:46:40 volumio volumio[2193]: info: Adding plugin smart_inputs to MyMusic Plugins May 05 01:46:40 volumio volumio[2193]: info: Adding plugin tidalconnect to MyMusic Plugins May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 05 01:46:40 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:46:40 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:46:40 volumio volumio[2193]: info: Starting MyVolumio Remote Streaming Endpoints May 05 01:46:40 volumio volumio[2193]: info: MyVolumio not started May 05 01:46:40 volumio volumio[2193]: info: Initializing device activation check May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 05 01:46:40 volumio volumio[2193]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 05 01:46:40 volumio volumio[2193]: info: Streaming services startup May 05 01:46:40 volumio volumio[2193]: info: Starting Streaming Daemon May 05 01:46:40 volumio sudo[2366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 01:46:40 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:40 volumio systemd[1]: Started Volumio Streaming Daemon. May 05 01:46:40 volumio systemd[2370]: volumio-streaming-daemon.service: Failed to execute command: No such file or directory May 05 01:46:40 volumio systemd[2370]: volumio-streaming-daemon.service: Failed at step EXEC spawning /bin/streaming-daemon: No such file or directory May 05 01:46:40 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=exited, status=203/EXEC May 05 01:46:40 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root May 05 01:46:40 volumio systemd[1]: volumio-streaming-daemon.service: Failed with result 'exit-code'. May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Service RestartSec=100ms expired, scheduling restart. May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Scheduled restart job, restart counter is at 1. May 05 01:46:41 volumio systemd[1]: Stopped Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[1]: Started Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[2373]: volumio-streaming-daemon.service: Failed to execute command: No such file or directory May 05 01:46:41 volumio systemd[2373]: volumio-streaming-daemon.service: Failed at step EXEC spawning /bin/streaming-daemon: No such file or directory May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=exited, status=203/EXEC May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Failed with result 'exit-code'. May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Service RestartSec=100ms expired, scheduling restart. May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Scheduled restart job, restart counter is at 2. May 05 01:46:41 volumio systemd[1]: Stopped Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[1]: Started Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[2376]: volumio-streaming-daemon.service: Failed to execute command: No such file or directory May 05 01:46:41 volumio systemd[2376]: volumio-streaming-daemon.service: Failed at step EXEC spawning /bin/streaming-daemon: No such file or directory May 05 01:46:41 volumio sudo[2375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=exited, status=203/EXEC May 05 01:46:41 volumio sudo[2375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Failed with result 'exit-code'. May 05 01:46:41 volumio sudo[2375]: pam_unix(sudo:session): session closed for user root May 05 01:46:41 volumio sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:46:41 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:41 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root May 05 01:46:41 volumio sudo[2382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 05 01:46:41 volumio sudo[2382]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:46:41 volumio sudo[2382]: pam_unix(sudo:session): session closed for user root May 05 01:46:41 volumio volumio[2193]: info: Upmpdcli Daemon Started May 05 01:46:41 volumio volumio[2193]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Service RestartSec=100ms expired, scheduling restart. May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Scheduled restart job, restart counter is at 3. May 05 01:46:41 volumio systemd[1]: Stopped Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[1]: Started Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[2384]: volumio-streaming-daemon.service: Failed to execute command: No such file or directory May 05 01:46:41 volumio systemd[2384]: volumio-streaming-daemon.service: Failed at step EXEC spawning /bin/streaming-daemon: No such file or directory May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=exited, status=203/EXEC May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Failed with result 'exit-code'. May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Service RestartSec=100ms expired, scheduling restart. May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Scheduled restart job, restart counter is at 4. May 05 01:46:41 volumio systemd[1]: Stopped Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[1]: Started Volumio Streaming Daemon. May 05 01:46:41 volumio systemd[2385]: volumio-streaming-daemon.service: Failed to execute command: No such file or directory May 05 01:46:41 volumio systemd[2385]: volumio-streaming-daemon.service: Failed at step EXEC spawning /bin/streaming-daemon: No such file or directory May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=exited, status=203/EXEC May 05 01:46:41 volumio systemd[1]: volumio-streaming-daemon.service: Failed with result 'exit-code'. May 05 01:46:42 volumio systemd[1]: volumio-streaming-daemon.service: Service RestartSec=100ms expired, scheduling restart. May 05 01:46:42 volumio systemd[1]: volumio-streaming-daemon.service: Scheduled restart job, restart counter is at 5. May 05 01:46:42 volumio systemd[1]: Stopped Volumio Streaming Daemon. May 05 01:46:42 volumio systemd[1]: volumio-streaming-daemon.service: Start request repeated too quickly. May 05 01:46:42 volumio systemd[1]: volumio-streaming-daemon.service: Failed with result 'exit-code'. May 05 01:46:42 volumio systemd[1]: Failed to start Volumio Streaming Daemon. May 05 01:46:43 volumio volumio[2193]: error: Failed initialization of streaming services: Error: An error occurred while retrieving enabled services. May 05 01:46:46 volumio volumio[2193]: info: Cannot mount NAS music at system boot, trial number 4 ,retrying in 5 seconds May 05 01:46:51 volumio volumio[2193]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 05 01:46:53 volumio nmbd[713]: [2024/05/05 01:46:53.948964, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 05 01:46:53 volumio nmbd[713]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.6 for name WORKGROUP<1d>. May 05 01:46:53 volumio nmbd[713]: This response was from IP 192.168.3.10, reporting an IP address of 192.168.3.10. May 05 01:47:00 volumio volumio[2193]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 05 01:47:00 volumio volumio[2193]: info: Completed starting MyVolumio Plugin May 05 01:47:00 volumio volumio[2193]: info: BOOT COMPLETED May 05 01:47:00 volumio volumio[2193]: [Metrics] CommandRouter: 29s 810.72ms May 05 01:47:00 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:47:00 volumio volumio[2193]: aplay: main:828: audio open error: Device or resource busy May 05 01:47:00 volumio volumio[2193]: Cannot play startup sound May 05 01:47:00 volumio volumio[2193]: info: CoreCommandRouter::Close All Modals sent May 05 01:47:00 volumio volumio[2193]: info: CoreCommandRouter::Close All Modals sent May 05 01:47:01 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 05 01:47:01 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 05 01:47:01 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 05 01:47:02 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:47:02 volumio volumio[2193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:47:33 volumio volumio[2193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:47:33 volumio volumio[2193]: Error: Client network socket disconnected before secure TLS connection was established May 05 01:47:33 volumio volumio[2193]: at connResetException (internal/errors.js:639:14) May 05 01:47:33 volumio volumio[2193]: at TLSSocket.onConnectEnd (_tls_wrap.js:1570:19) May 05 01:47:33 volumio volumio[2193]: at TLSSocket.emit (events.js:412:35) May 05 01:47:33 volumio volumio[2193]: at endReadableNT (internal/streams/readable.js:1334:12) May 05 01:47:33 volumio volumio[2193]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { May 05 01:47:33 volumio volumio[2193]: code: 'ECONNRESET', May 05 01:47:33 volumio volumio[2193]: path: null, May 05 01:47:33 volumio volumio[2193]: host: 'lastfm.freetls.fastly.net', May 05 01:47:33 volumio volumio[2193]: port: 443, May 05 01:47:33 volumio volumio[2193]: localAddress: undefined May 05 01:47:33 volumio volumio[2193]: } May 05 01:47:33 volumio volumio[2193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:47:33 volumio sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-05 01:46 May 05 01:47:33 volumio sudo[2403]: 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="e3cf1b233cf57bc02fe2950d88f24df9f8386602" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_FE3_VERSION="2c893652079de9d2fda4c908ff5de8f733b655f0" VOLUMIO_BE_VERSION="befca4f2e90ccef85fdd55007307cda78c6b3ce9" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumiobuster" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Oct 2021 06:15:37 PM CEST" VOLUMIO_VERSION="3.111" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2dc494284b30740b4e47078de84c87aa"