Dec 10 20:21:28 volumio volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 20:21:28 volumio volumio[1460]: info: CURURI: music-library Dec 10 20:21:28 volumio volumio[1460]: info: Preload queue cleared Dec 10 20:21:32 volumio volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 20:21:32 volumio volumio[1460]: info: CURURI: music-library/NAS Dec 10 20:21:32 volumio volumio[1460]: info: Preload queue cleared Dec 10 20:21:33 volumio volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 20:21:33 volumio volumio[1460]: info: CURURI: music-library/NAS/NAS Dec 10 20:21:33 volumio volumio[1460]: error: Failed LSINFO: null Dec 10 20:21:34 volumio volumio[1460]: info: Preload queue cleared Dec 10 20:21:37 volumio volumio[1460]: info: CorePlayQueue::getTrack 1 Dec 10 20:21:37 volumio volumio[1460]: info: CorePlayQueue::getTrack 2 Dec 10 20:21:37 volumio volumio[1460]: info: Prefetching next song Dec 10 20:21:37 volumio volumio[1460]: info: Doing Prefetch in UPNP Dec 10 20:21:37 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.80:8200/MediaItems/28.wav" Dec 10 20:21:37 volumio volumio[1460]: info: Dec 10 20:21:37 volumio volumio[1460]: ---------------------------- MPD announces system playlist update Dec 10 20:21:37 volumio volumio[1460]: info: Ignoring MPD Status Update Dec 10 20:21:37 volumio volumio[1460]: info: sendMpdCommand add "http://10.0.0.80:8200/MediaItems/28.wav" took 1 milliseconds Dec 10 20:21:37 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 10 20:21:37 volumio volumio[1460]: info: Dec 10 20:21:37 volumio volumio[1460]: ---------------------------- MPD announces system playlist update Dec 10 20:21:37 volumio volumio[1460]: info: Ignoring MPD Status Update Dec 10 20:21:37 volumio volumio[1460]: info: Dec 10 20:21:37 volumio volumio[1460]: ---------------------------- MPD announces system playlist update Dec 10 20:21:37 volumio volumio[1460]: info: Ignoring MPD Status Update Dec 10 20:21:37 volumio volumio[1460]: info: ------------------------------ 1ms Dec 10 20:21:37 volumio volumio[1460]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 10 20:21:37 volumio volumio[1460]: info: ------------------------------ 1ms Dec 10 20:21:37 volumio volumio[1460]: info: ------------------------------ 1ms Dec 10 20:21:39 volumio volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 20:21:39 volumio volumio[1460]: info: CURURI: music-library/INTERNAL Dec 10 20:21:39 volumio volumio[1460]: info: Preload queue cleared Dec 10 20:21:39 volumio volumio[1460]: info: Preloading song: music-library/INTERNAL/雷婷-监听终极版(黄金黑胶)CD1.ape Dec 10 20:21:39 volumio volumio[1460]: info: Preloading song: music-library/INTERNAL/01. 加州旅馆.wav Dec 10 20:21:39 volumio volumio[1460]: info: Exploding uri music-library/INTERNAL/雷婷-监听终极版(黄金黑胶)CD1.ape in service mpd Dec 10 20:21:39 volumio volumio[1460]: info: ALBUMART /albumart?cacheid=42&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%9B%B7%E5%A9%B7-%E7%9B%91%E5%90%AC%E7%BB%88%E6%9E%81%E7%89%88(%E9%BB%84%E9%87%91%E9%BB%91%E8%83%B6)CD1.ape&metadata=false Dec 10 20:21:39 volumio volumio[1460]: info: URI /mnt/INTERNAL/雷婷-监听终极版(黄金黑胶)CD1.ape Dec 10 20:21:39 volumio volumio[1460]: info: Exploding uri music-library/INTERNAL/01. 加州旅馆.wav in service mpd Dec 10 20:21:39 volumio volumio[1460]: info: ALBUMART /albumart?cacheid=42&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F01.%20%E5%8A%A0%E5%B7%9E%E6%97%85%E9%A6%86.wav&metadata=false Dec 10 20:21:39 volumio volumio[1460]: info: URI /mnt/INTERNAL/01. 加州旅馆.wav Dec 10 20:21:41 volumio volumio[1460]: info: Dec 10 20:21:41 volumio volumio[1460]: ---------------------------- MPD announces system playlist update Dec 10 20:21:41 volumio volumio[1460]: info: Ignoring MPD Status Update Dec 10 20:21:41 volumio volumio[1460]: info: Dec 10 20:21:41 volumio volumio[1460]: ---------------------------- MPD announces state update: player Dec 10 20:21:41 volumio volumio[1460]: info: ControllerMpd::getState Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 10 20:21:41 volumio volumio[1460]: info: Dec 10 20:21:41 volumio volumio[1460]: ---------------------------- MPD announces system playlist update Dec 10 20:21:41 volumio volumio[1460]: info: Ignoring MPD Status Update Dec 10 20:21:41 volumio volumio[1460]: info: Dec 10 20:21:41 volumio volumio[1460]: ---------------------------- MPD announces state update: player Dec 10 20:21:41 volumio volumio[1460]: info: ControllerMpd::getState Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 10 20:21:41 volumio volumio[1460]: info: Dec 10 20:21:41 volumio volumio[1460]: ---------------------------- MPD announces system playlist update Dec 10 20:21:41 volumio volumio[1460]: info: Ignoring MPD Status Update Dec 10 20:21:41 volumio volumio[1460]: info: Dec 10 20:21:41 volumio volumio[1460]: ---------------------------- MPD announces state update: player Dec 10 20:21:41 volumio volumio[1460]: info: ControllerMpd::getState Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 10 20:21:41 volumio volumio[1460]: info: ------------------------------ 2ms Dec 10 20:21:41 volumio volumio[1460]: info: sendMpdCommand status took 1 milliseconds Dec 10 20:21:41 volumio volumio[1460]: info: ------------------------------ 1ms Dec 10 20:21:41 volumio volumio[1460]: info: sendMpdCommand status took 1 milliseconds Dec 10 20:21:41 volumio volumio[1460]: info: ------------------------------ 1ms Dec 10 20:21:41 volumio volumio[1460]: info: sendMpdCommand status took 0 milliseconds Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::parseState Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::parseState Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::parseState Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 20:21:41 volumio volumio[1460]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 10 20:21:41 volumio volumio[1460]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 10 20:21:41 volumio volumio[1460]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 10 20:21:41 volumio volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 10 20:21:41 volumio volumio[1460]: info: ControllerMpd::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::servicePushState Dec 10 20:21:41 volumio volumio[1460]: info: CorePlayQueue::getTrack 1 Dec 10 20:21:41 volumio volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":59,"duration":295,"samplerate":44.1,"bitdepth":"32 bit","channels":7,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"28.wav","artist":null,"album":null,"uri":"http://10.0.0.80:8200/MediaItems/28.wav","trackType":"wav"} Dec 10 20:21:41 volumio volumio[1460]: verbose: CURRENT POSITION 1 Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 10 20:21:41 volumio volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:41 volumio volumio[1460]: info: ControllerMpd::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::servicePushState Dec 10 20:21:41 volumio volumio[1460]: info: CorePlayQueue::getTrack 1 Dec 10 20:21:41 volumio volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94,"duration":295,"samplerate":44.1,"bitdepth":"32 bit","channels":7,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"28.wav","artist":null,"album":null,"uri":"http://10.0.0.80:8200/MediaItems/28.wav","trackType":"wav"} Dec 10 20:21:41 volumio volumio[1460]: verbose: CURRENT POSITION 1 Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 10 20:21:41 volumio volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:41 volumio volumio[1460]: info: ControllerMpd::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::servicePushState Dec 10 20:21:41 volumio volumio[1460]: info: CorePlayQueue::getTrack 1 Dec 10 20:21:41 volumio volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94,"duration":295,"samplerate":44.1,"bitdepth":"32 bit","channels":7,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"28.wav","artist":null,"album":null,"uri":"http://10.0.0.80:8200/MediaItems/28.wav","trackType":"wav"} Dec 10 20:21:41 volumio volumio[1460]: verbose: CURRENT POSITION 1 Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 10 20:21:41 volumio volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:41 volumio volumio[1460]: info: ------------------------------ 21ms Dec 10 20:21:41 volumio volumio[1460]: info: ------------------------------ 20ms Dec 10 20:21:41 volumio volumio[1460]: info: ------------------------------ 20ms Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::startPlaybackTimer Dec 10 20:21:41 volumio volumio[1460]: info: CorePlayQueue::getTrack 2 Dec 10 20:21:41 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:41 volumio volumio[1460]: info: CorePlayQueue::getTrack 2 Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 20:21:41 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:45 volumio volumio[1460]: info: Preload queue cleared Dec 10 20:21:45 volumio volumio[1460]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::ClearQueue Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::stop Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::stPlaybackTimer Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::updateTrackBlock Dec 10 20:21:45 volumio volumio[1460]: info: CorePlayQueue::getTrackBlock Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::pushState Dec 10 20:21:45 volumio volumio[1460]: info: CorePlayQueue::getTrack 2 Dec 10 20:21:45 volumio volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 20:21:45 volumio volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::serviceStop Dec 10 20:21:45 volumio volumio[1460]: info: CorePlayQueue::getTrack 2 Dec 10 20:21:45 volumio volumio[1460]: info: CoreCommandRouter::serviceStop Dec 10 20:21:45 volumio volumio[1460]: info: [1765426905866] ControllerUPNPBrowser::stop Dec 10 20:21:45 volumio volumio[1460]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 20:21:45 volumio volumio[1460]: info: CorePlayQueue::clearPlayQueue Dec 10 20:21:45 volumio volumio[1460]: info: CorePlayQueue::saveQueue Dec 10 20:21:45 volumio volumio[1460]: info: CoreCommandRouter::volumioPushQueue Dec 10 20:21:45 volumio volumio[1460]: info: CoreStateMachine::addQueueItems Dec 10 20:21:45 volumio volumio[1460]: info: CorePlayQueue::addQueueItems Dec 10 20:21:45 volumio volumio[1460]: info: Preload queue cleared Dec 10 20:21:45 volumio volumio[1460]: info: Adding Item to queue: music-library/INTERNAL/绝对发烧 Dec 10 20:21:45 volumio volumio[1460]: info: Exploding uri music-library/INTERNAL/绝对发烧 in service mpd Dec 10 20:21:45 volumio volumio[1460]: info: Dec 10 20:21:45 volumio volumio[1460]: ---------------------------- MPD announces state update: player Dec 10 20:21:45 volumio volumio[1460]: info: ALBUMART /albumart?cacheid=42&web=/%3F%3F%3F%3F%3Fo%3FN/extralarge&path=%2Fmnt%2FINTERNAL%2F%E7%BB%9D%E5%AF%B9%E5%8F%91%E7%83%A7%2F%E7%BE%A4%E6%98%9F.-.%5B%E7%BB%9D%E5%AF%B9%E5%8F%91%E7%83%A7%5D.%E4%B8%93%E8%BE%91.(ape).ape&metadata=false Dec 10 20:21:45 volumio volumio[1460]: info: URI /mnt/INTERNAL/绝对发烧/群星.-.[绝对发烧].专辑.(ape).ape Dec 10 20:21:45 volumio volumio[1460]: info: ALBUMART /albumart?cacheid=42&web=%3F%3F%3F%3F%3F%3F%D6%AA%3F%3F/%3F%5E%3F%3F%3Fl%3F%3F/extralarge&path=%2Fmnt%2FINTERNAL%2F%E7%BB%9D%E5%AF%B9%E5%8F%91%E7%83%A7%2F%E7%BE%A4%E6%98%9F.-.%5B%E7%BB%9D%E5%AF%B9%E5%8F%91%E7%83%A7%5D.%E4%B8%93%E8%BE%91.(cue).cue&metadata=false Dec 10 20:21:45 volumio volumio[1460]: info: URI /mnt/INTERNAL/绝对发烧/群星.-.[绝对发烧].专辑.(cue).cue Dec 10 20:21:45 volumio volumio[1460]: info: ALBUMART /albumart?cacheid=42&web=%3F%3F%3F%3F%3F%3F%3F%3F%3F%3F/%3F%5E%3F%3F%3Fl%3F%3F/extralarge&path=%2Fmnt%2FINTERNAL%2F%E7%BB%9D%E5%AF%B9%E5%8F%91%E7%83%A7%2F%E7%BE%A4%E6%98%9F.-.%5B%E7%BB%9D%E5%AF%B9%E5%8F%91%E7%83%A7%5D.%E4%B8%93%E8%BE%91.(cue).cue&metadata=false Dec 10 20:21:45 volumio volumio[1460]: info: URI /mnt/INTERNAL/绝对发烧/群星.-.[绝对发烧].专辑.(cue).cue Dec 10 20:21:45 volumio volumio[1460]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 20:21:45 volumio volumio[1460]: Error: Unable to resolve or reject the same promise twice Dec 10 20:21:45 volumio volumio[1460]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 10 20:21:45 volumio volumio[1460]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 10 20:21:45 volumio volumio[1460]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 10 20:21:45 volumio volumio[1460]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 10 20:21:45 volumio volumio[1460]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 10 20:21:45 volumio volumio[1460]: at Socket.emit (node:events:514:28) Dec 10 20:21:45 volumio volumio[1460]: at addChunk (node:internal/streams/readable:343:12) Dec 10 20:21:45 volumio volumio[1460]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 10 20:21:45 volumio volumio[1460]: at Readable.push (node:internal/streams/readable:253:10) Dec 10 20:21:45 volumio volumio[1460]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 10 20:21:45 volumio volumio[1460]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 20:21:46 volumio sudo[5988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 20:20' Dec 10 20:21:46 volumio sudo[5988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:46 volumio sudo[5988]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:46 volumio volumio-remote-updater[943]: [2025-12-10 20:21:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 10 20:21:46 volumio volumio-remote-updater[943]: [2025-12-10 20:21:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 10 20:21:46 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 10 20:21:46 volumio systemd[1]: media-Music.mount: Deactivated successfully. Dec 10 20:21:46 volumio ntfs-3g[1576]: Unmounting /dev/sda1 (Music) Dec 10 20:21:46 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 10 20:21:46 volumio systemd[1]: volumio.service: Consumed 15.159s CPU time. Dec 10 20:21:46 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 10 20:21:46 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 10 20:21:47 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 10 20:21:47 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 10 20:21:47 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 10 20:21:47 volumio systemd[1]: volumio.service: Consumed 15.159s CPU time. Dec 10 20:21:47 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 10 20:21:47 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 10 20:21:47 volumio volumio[6031]: info: ------------------------------------------- Dec 10 20:21:47 volumio volumio[6031]: info: ----- Volumio3 ---- Dec 10 20:21:47 volumio volumio[6031]: info: ------------------------------------------- Dec 10 20:21:47 volumio volumio[6031]: info: ----- System startup ---- Dec 10 20:21:47 volumio volumio[6031]: info: ------------------------------------------- Dec 10 20:21:47 volumio volumio[6031]: info: MYVOLUMIO Environment detected Dec 10 20:21:47 volumio volumio[6031]: info: Plugin folders cleanup Dec 10 20:21:47 volumio volumio[6031]: info: Scanning into folder /volumio/app/plugins/ Dec 10 20:21:47 volumio volumio[6031]: info: Scanning category audio_interface Dec 10 20:21:47 volumio volumio[6031]: info: Scanning category miscellanea Dec 10 20:21:47 volumio volumio[6031]: info: Scanning category music_service Dec 10 20:21:47 volumio volumio[6031]: info: Scanning category plugins.json Dec 10 20:21:47 volumio volumio[6031]: info: Scanning category system_controller Dec 10 20:21:47 volumio volumio[6031]: info: Scanning category user_interface Dec 10 20:21:47 volumio volumio[6031]: info: Scanning into folder /data/plugins/ Dec 10 20:21:47 volumio volumio[6031]: info: Plugin folders cleanup completed Dec 10 20:21:47 volumio volumio[6031]: info: ------------------------------------------- Dec 10 20:21:47 volumio volumio[6031]: info: ----- Core plugins startup ---- Dec 10 20:21:47 volumio volumio[6031]: info: ------------------------------------------- Dec 10 20:21:47 volumio volumio[6031]: info: Loading plugins from folder /volumio/app/plugins/ Dec 10 20:21:47 volumio volumio[6031]: info: Adding plugin upnp to MyMusic Plugins Dec 10 20:21:47 volumio volumio[6031]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 10 20:21:47 volumio volumio[6031]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 10 20:21:47 volumio volumio[6031]: info: Loading plugins from folder /data/plugins/ Dec 10 20:21:47 volumio volumio[6031]: info: Loading plugin "system"... Dec 10 20:21:47 volumio volumio[6031]: info: Loading plugin "appearance"... Dec 10 20:21:48 volumio volumio[6031]: info: Loading plugin "network"... Dec 10 20:21:48 volumio volumio[6031]: info: Refreshing Cached IP Addresses Dec 10 20:21:48 volumio sudo[6059]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 20:21:48 volumio sudo[6059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:48 volumio sudo[6061]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 20:21:48 volumio sudo[6059]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:48 volumio sudo[6061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:48 volumio sudo[6061]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:48 volumio volumio[6031]: info: Loading plugin "services"... Dec 10 20:21:48 volumio volumio[6031]: info: Loading plugin "alsa_controller"... Dec 10 20:21:48 volumio sudo[6070]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 10 20:21:48 volumio sudo[6070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:48 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 20:21:48 volumio volumio[6031]: info: Loading plugin "wizard"... Dec 10 20:21:48 volumio volumio[6031]: info: Loading plugin "networkfs"... Dec 10 20:21:48 volumio volumio[6031]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Dec 10 20:21:48 volumio volumio[6031]: info: Starting Udev Watcher for removable devices Dec 10 20:21:48 volumio volumio[6031]: info: Mounting Device Music Dec 10 20:21:48 volumio sudo[6095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Music -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 10 20:21:48 volumio sudo[6095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:49 volumio ntfs-3g[6098]: Version 2022.10.3 integrated FUSE 28 Dec 10 20:21:49 volumio ntfs-3g[6098]: Mounted /dev/sda1 (Read-Write, label "Music", NTFS 3.1) Dec 10 20:21:49 volumio ntfs-3g[6098]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 10 20:21:49 volumio ntfs-3g[6098]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 10 20:21:49 volumio ntfs-3g[6098]: Global ownership and permissions enforced, configuration type 7 Dec 10 20:21:49 volumio sudo[6095]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:49 volumio volumio[6031]: info: Ignoring mount for partition: boot Dec 10 20:21:49 volumio volumio[6031]: info: Ignoring mount for partition: volumio Dec 10 20:21:49 volumio volumio[6031]: info: Ignoring mount for partition: volumio_data Dec 10 20:21:49 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "volumio_command_line_client"... Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "upnp"... Dec 10 20:21:49 volumio volumio[6031]: info: [1765426909206] Starting Upmpd Daemon Dec 10 20:21:49 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "my_music"... Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "mpd"... Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "upnp_browser"... Dec 10 20:21:49 volumio volumio[6031]: info: Starting UPNP Browser Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "alarm-clock"... Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "airplay_emulation"... Dec 10 20:21:49 volumio volumio[6031]: info: Starting Shairport Sync Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "last_100"... Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "webradio"... Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "i2s_dacs"... Dec 10 20:21:49 volumio volumio[6031]: info: I2S DAC not set, start Auto-detection Dec 10 20:21:49 volumio volumio[6031]: info: Loading plugin "volumiodiscovery"... Dec 10 20:21:49 volumio volumio[6031]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 20:21:49 volumio volumio[6031]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 20:21:49 volumio volumio[6031]: *** WARNING *** For more information see Dec 10 20:21:49 volumio volumio[6031]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 20:21:49 volumio node[6031]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 10 20:21:49 volumio volumio[6031]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 20:21:49 volumio volumio[6031]: *** WARNING *** For more information see Dec 10 20:21:49 volumio node[6031]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 20:21:49 volumio node[6031]: *** WARNING *** For more information see Dec 10 20:21:49 volumio node[6031]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 10 20:21:49 volumio node[6031]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 10 20:21:49 volumio node[6031]: *** WARNING *** For more information see Dec 10 20:21:49 volumio volumio[6031]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 10 20:21:50 volumio volumio[6031]: info: Discovery: Started advertising with name: Volumio Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 10 20:21:50 volumio volumio[6031]: info: Loading plugin "outputs"... Dec 10 20:21:50 volumio volumio[6031]: info: Loading plugin "albumart"... Dec 10 20:21:50 volumio volumio[6031]: info: Plugin example_plugin is not enabled Dec 10 20:21:50 volumio volumio[6031]: info: Loading plugin "inputs"... Dec 10 20:21:50 volumio volumio[6031]: info: Loading plugin "updater_comm"... Dec 10 20:21:50 volumio volumio[6031]: info: Plugin mpdemulation is not enabled Dec 10 20:21:50 volumio volumio[6031]: info: Loading plugin "rest_api"... Dec 10 20:21:50 volumio volumio[6031]: info: Loading plugin "websocket"... Dec 10 20:21:50 volumio volumio[6031]: info: Starting Socket.io Server version 1.7.4 Dec 10 20:21:50 volumio volumio[6031]: info: Loading i18n strings for locale zh Dec 10 20:21:50 volumio volumio[6031]: Updating browse sources language Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::initPlayerControls Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: Express server listening on port 3000 Dec 10 20:21:50 volumio volumio[6031]: [Metrics] WebUI: 2s 616.09ms Dec 10 20:21:50 volumio volumio[6031]: info: CoreStateMachine::resetVolumioState Dec 10 20:21:50 volumio volumio[6031]: info: CoreStateMachine::getcurrentVolume Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 20:21:50 volumio volumio[6031]: info: CoreStateMachine::pushState Dec 10 20:21:50 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: Volumio Network Manager: Network status updated: 2 Dec 10 20:21:50 volumio volumio[6031]: verbose: New Socket.io Connection to 10.0.0.116 from 10.0.0.140 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 1 Dec 10 20:21:50 volumio volumio[6102]: Forking 3 albumart workers Dec 10 20:21:50 volumio volumio[6031]: info: Reloading queue from file Dec 10 20:21:50 volumio volumio[6031]: info: CoreStateMachine::setRepeat null single undefined Dec 10 20:21:50 volumio volumio[6031]: info: CoreStateMachine::pushState Dec 10 20:21:50 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:50 volumio volumio[6031]: info: CoreStateMachine::setRandom null Dec 10 20:21:50 volumio volumio[6031]: info: CoreStateMachine::pushState Dec 10 20:21:50 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioPushState Dec 10 20:21:50 volumio volumio[6031]: info: Setting Device type: Raspberry PI Dec 10 20:21:50 volumio volumio[6031]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 10 20:21:50 volumio volumio[6031]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Dec 10 20:21:50 volumio volumio[6031]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 10 20:21:50 volumio volumio[6031]: info: Completed loading Core Plugins Dec 10 20:21:50 volumio volumio[6031]: info: Preparing to generate the ALSA configuration file Dec 10 20:21:50 volumio volumio[6031]: info: Asound.conf file unchanged, so no further update is needed Dec 10 20:21:50 volumio volumio[6031]: info: Output device has changed, restarting MPD Dec 10 20:21:50 volumio volumio[6031]: info: Output device has changed, restarting Shairport Sync Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 20:21:50 volumio sudo[6155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 20:21:50 volumio sudo[6155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:50 volumio sudo[6153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 20:21:50 volumio sudo[6153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:50 volumio sudo[6153]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:50 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 20:21:50 volumio volumio[6031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 20:21:50 volumio volumio[6031]: info: ___________ START PLUGINS ___________ Dec 10 20:21:50 volumio volumio[6031]: info: ControllerMpd::onStart: Initializing MPD Dec 10 20:21:50 volumio volumio[6031]: info: Creating MPD Configuration file Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 20:21:50 volumio volumio[6031]: info: [1765426910329] CoreMusicLibrary::Adding element 媒体服务器 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 20:21:50 volumio sudo[6165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 20:21:50 volumio sudo[6165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:50 volumio sudo[6165]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:50 volumio volumio[6031]: info: UPNP Browser: Client initialized successfully Dec 10 20:21:50 volumio sudo[6167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 20:21:50 volumio sudo[6167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 20:21:50 volumio volumio[6031]: info: [1765426910383] CoreMusicLibrary::Adding element Last_100 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 20:21:50 volumio volumio[6031]: info: [1765426910384] CoreMusicLibrary::Adding element Webradio Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 20:21:50 volumio volumio[6031]: info: Initializing BBC Radios Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: Volumio Calling Home Dec 10 20:21:50 volumio volumio[6121]: Starting albumart workers Dec 10 20:21:50 volumio volumio[6031]: info: MPD Permissions set Dec 10 20:21:50 volumio volumio[6031]: info: MPD Permissions set Dec 10 20:21:50 volumio volumio[6031]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6122]: Starting albumart workers Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6123]: Starting albumart workers Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 20:21:50 volumio volumio[6031]: info: Starting Shairport Sync Dec 10 20:21:50 volumio volumio[6031]: info: Starting Shairport Sync Dec 10 20:21:50 volumio volumio[6031]: info: Starting Shairport Sync Dec 10 20:21:50 volumio sudo[6205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 20:21:50 volumio sudo[6205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:50 volumio sudo[6207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 20:21:50 volumio sudo[6209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 20:21:50 volumio sudo[6209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:50 volumio sudo[6207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:50 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 20:21:50 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 20:21:50 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 20:21:50 volumio systemd[1]: shairport-sync.service: Consumed 2.682s CPU time. Dec 10 20:21:50 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 20:21:50 volumio sudo[6209]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:50 volumio sudo[6205]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:50 volumio sudo[6207]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:50 volumio volumio[6031]: info: Shairport-Sync Started Dec 10 20:21:50 volumio volumio[6031]: Error adding Membership: Error: addMembership EINVAL Dec 10 20:21:50 volumio volumio[6031]: info: Shairport-Sync Started Dec 10 20:21:50 volumio volumio[6031]: info: Shairport-Sync Started Dec 10 20:21:50 volumio volumio[6031]: info: Volumio called home Dec 10 20:21:50 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:21:50 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:51 volumio volumio[6031]: info: Discovery: adding 5027547f-befb-48e1-901f-2daac436aec9 Dec 10 20:21:51 volumio volumio[6031]: info: Discovery: Found device Volumio Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:21:51 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:51 volumio volumio[6031]: info: Discovery: this is already registered, 5027547f-befb-48e1-901f-2daac436aec9 Dec 10 20:21:51 volumio volumio[6031]: info: Discovery: Found device Volumio Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:21:51 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:51 volumio volumio[6031]: verbose: New Socket.io Connection to 10.0.0.116 from 10.0.0.140 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 3 Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:21:51 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:21:51 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 10 20:21:51 volumio volumio[6031]: info: Received Get System Info Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 20:21:51 volumio volumio[6031]: info: Discovery: Getting this device information Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:21:51 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:21:51 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:21:51 volumio volumio[6031]: info: Listing playlists Dec 10 20:21:51 volumio volumio[6031]: info: CoreCommandRouter::volumioGetQueue Dec 10 20:21:51 volumio volumio[6031]: info: CoreStateMachine::getQueue Dec 10 20:21:51 volumio volumio[6031]: info: CorePlayQueue::getQueue Dec 10 20:21:51 volumio sudo[6070]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:51 volumio volumio-remote-updater[943]: [2025-12-10 20:21:51] [connect] Successful connection Dec 10 20:21:51 volumio volumio-remote-updater[943]: [2025-12-10 20:21:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765426911 101 Dec 10 20:21:51 volumio volumio[6031]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Dec 10 20:21:53 volumio volumio[6031]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Dec 10 20:21:58 volumio volumio[6031]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Dec 10 20:21:58 volumio volumio[6031]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 20:21:58 volumio volumio[6031]: info: CURURI: music-library/INTERNAL/绝对发烧 Dec 10 20:21:58 volumio volumio[6031]: error: Failed to execute browseSource, failure: TypeError: Cannot read properties of undefined (reading 'then') Dec 10 20:21:59 volumio sudo[6282]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 20:21:59 volumio sudo[6282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:59 volumio sudo[6284]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 20:21:59 volumio sudo[6282]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:59 volumio sudo[6284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:59 volumio sudo[6284]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:59 volumio sudo[6287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 10 20:21:59 volumio sudo[6287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 20:21:59 volumio sudo[6287]: pam_unix(sudo:session): session closed for user root Dec 10 20:21:59 volumio volumio[6031]: info: Upmpdcli Daemon Started Dec 10 20:22:02 volumio volumio[6031]: info: Preload queue cleared Dec 10 20:22:02 volumio volumio[6031]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::ClearQueue Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::stop Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 20:22:02 volumio volumio[6031]: info: CorePlayQueue::clearPlayQueue Dec 10 20:22:02 volumio volumio[6031]: info: CorePlayQueue::saveQueue Dec 10 20:22:02 volumio volumio[6031]: info: CoreCommandRouter::volumioPushQueue Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::addQueueItems Dec 10 20:22:02 volumio volumio[6031]: info: CorePlayQueue::addQueueItems Dec 10 20:22:02 volumio volumio[6031]: info: Preload queue cleared Dec 10 20:22:02 volumio volumio[6031]: info: Adding Item to queue: music-library/INTERNAL/绝对发烧 Dec 10 20:22:02 volumio volumio[6031]: info: Exploding uri music-library/INTERNAL/绝对发烧 in service mpd Dec 10 20:22:02 volumio volumio[6031]: error: Failed to stat '/mnt/INTERNAL/绝对发烧' Dec 10 20:22:02 volumio volumio[6031]: info: CoreCommandRouter::volumioPushQueue Dec 10 20:22:02 volumio volumio[6031]: info: CorePlayQueue::saveQueue Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::updateTrackBlock Dec 10 20:22:02 volumio volumio[6031]: info: CorePlayQueue::getTrackBlock Dec 10 20:22:02 volumio volumio[6031]: info: CoreCommandRouter::volumioPlay Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::play index 0 Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::stop Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::play index undefined Dec 10 20:22:02 volumio volumio[6031]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 20:22:02 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:22:03 volumio volumio[6031]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Dec 10 20:22:05 volumio volumio[6031]: info: CoreCommandRouter::volumioGetState Dec 10 20:22:05 volumio volumio[6031]: info: CorePlayQueue::getTrack 0 Dec 10 20:22:08 volumio volumio[6031]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 10 20:22:11 volumio volumio[6031]: info: Preload queue cleared Dec 10 20:22:11 volumio volumio[6031]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 10 20:22:11 volumio volumio[6031]: info: CoreStateMachine::ClearQueue Dec 10 20:22:11 volumio volumio[6031]: info: CoreStateMachine::stop Dec 10 20:22:11 volumio volumio[6031]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 20:22:11 volumio volumio[6031]: info: CorePlayQueue::clearPlayQueue Dec 10 20:22:11 volumio volumio[6031]: info: CorePlayQueue::saveQueue Dec 10 20:22:11 volumio volumio[6031]: info: CoreCommandRouter::volumioPushQueue Dec 10 20:22:11 volumio volumio[6031]: info: CoreStateMachine::addQueueItems Dec 10 20:22:11 volumio volumio[6031]: info: CorePlayQueue::addQueueItems Dec 10 20:22:11 volumio volumio[6031]: info: Preload queue cleared Dec 10 20:22:11 volumio volumio[6031]: info: Adding Item to queue: music-library/INTERNAL/雷婷-监听终极版(黄金黑胶)CD1.ape Dec 10 20:22:11 volumio volumio[6031]: info: Exploding uri music-library/INTERNAL/雷婷-监听终极版(黄金黑胶)CD1.ape in service mpd Dec 10 20:22:11 volumio volumio[6031]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 20:22:11 volumio volumio[6031]: TypeError: Cannot read properties of undefined (reading 'then') Dec 10 20:22:11 volumio volumio[6031]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2546:19) Dec 10 20:22:11 volumio volumio[6031]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2344:21) Dec 10 20:22:11 volumio volumio[6031]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Dec 10 20:22:11 volumio volumio[6031]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Dec 10 20:22:11 volumio volumio[6031]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Dec 10 20:22:11 volumio volumio[6031]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Dec 10 20:22:11 volumio volumio[6031]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Dec 10 20:22:11 volumio volumio[6031]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Dec 10 20:22:11 volumio volumio[6031]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Dec 10 20:22:11 volumio volumio[6031]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Dec 10 20:22:11 volumio volumio[6031]: at Socket.emit (node:events:514:28) Dec 10 20:22:11 volumio volumio[6031]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 10 20:22:11 volumio volumio[6031]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 10 20:22:11 volumio volumio[6031]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 20:22:12 volumio sudo[6322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 20:21' Dec 10 20:22:12 volumio sudo[6322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"