Aug 28 16:43:09 volumio volumio[1167]: verbose: New Socket.io Connection to 192.168.28.195 from 192.168.28.148 UA: Mozilla/5.0 (Linux; Android 16; 25098PN5AC Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 28 16:43:09 volumio volumio[1167]: info: CorePlayQueue::getTrack 10 Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::volumioGetQueue Aug 28 16:43:09 volumio volumio[1167]: info: CoreStateMachine::getQueue Aug 28 16:43:09 volumio volumio[1167]: info: CorePlayQueue::getQueue Aug 28 16:43:09 volumio volumio[1167]: info: Listing playlists Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 16:43:09 volumio volumio[1167]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 16:43:09 volumio volumio[1167]: info: Received Get System Info Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 16:43:09 volumio volumio[1167]: info: Discovery: Getting this device information Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 28 16:43:09 volumio volumio[1167]: info: CorePlayQueue::getTrack 10 Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 28 16:43:09 volumio volumio[1167]: info: CorePlayQueue::getTrack 10 Aug 28 16:43:09 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 28 16:43:13 volumio nmbd[1122]: [2026/08/28 16:43:13.137363, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 28 16:43:13 volumio nmbd[1122]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.28.195 for name WORKGROUP<1d>. Aug 28 16:43:13 volumio nmbd[1122]: This response was from IP 192.168.28.216, reporting an IP address of 192.168.28.216. Aug 28 16:43:52 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:52.747+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s Aug 28 16:43:52 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 16:43:52 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 16:43:52 volumio volumio[1167]: info: Discovery: Getting this device information Aug 28 16:43:52 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 28 16:43:52 volumio volumio[1167]: info: CorePlayQueue::getTrack 10 Aug 28 16:43:52 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 16:43:52 volumio volumio[1167]: verbose: New Socket.io Connection to 192.168.28.195:3000 from 192.168.28.148 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 28 16:43:52 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 16:43:52 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 16:43:53 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:53.829+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=1.08223188s Aug 28 16:43:53 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:53.852+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://www.googleapis.com duration=1.104276609s Aug 28 16:43:53 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:53.870+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=http://pushupdates.volumio.org duration=1.120498141s Aug 28 16:43:53 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:53.981+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://securetoken.googleapis.com duration=1.232998985s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.055+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.306243024s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.061+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.311899538s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.090+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.341701093s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.092+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=1.344158326s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.321+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://database.volumio.cloud duration=1.571985707s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.408+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.659409762s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.684+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=https://google.com duration=1.934775399s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.817+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=http://plugins.volumio.org duration=2.068134116s Aug 28 16:43:54 volumio volumio5-onboarding[1476]: time=2026-08-28T16:43:54.901+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" latency=277.21462ms timeout=10s endpoint=http://cddb.volumio.org duration=2.152755737s Aug 28 16:44:00 volumio volumio[1167]: info: Preload queue cleared Aug 28 16:44:00 volumio volumio[1167]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::ClearQueue Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::stop Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::stPlaybackTimer Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::updateTrackBlock Aug 28 16:44:00 volumio volumio[1167]: info: CorePlayQueue::getTrackBlock Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::pushState Aug 28 16:44:00 volumio volumio[1167]: info: CorePlayQueue::getTrack 10 Aug 28 16:44:00 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:44:00 volumio volumio[1167]: info: CoreCommandRouter::volumioPushState Aug 28 16:44:00 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Aug 28 16:44:00 volumio volumio[1167]: info: CorePlayQueue::getTrack 10 Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::serviceStop Aug 28 16:44:00 volumio volumio[1167]: info: CorePlayQueue::getTrack 10 Aug 28 16:44:00 volumio volumio[1167]: info: CoreCommandRouter::serviceStop Aug 28 16:44:00 volumio volumio[1167]: info: ControllerMpd::stop Aug 28 16:44:00 volumio volumio[1167]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 16:44:00 volumio volumio[1167]: info: CorePlayQueue::clearPlayQueue Aug 28 16:44:00 volumio volumio[1167]: info: CorePlayQueue::saveQueue Aug 28 16:44:00 volumio volumio[1167]: info: CoreCommandRouter::volumioPushQueue Aug 28 16:44:00 volumio volumio[1167]: info: CoreStateMachine::addQueueItems Aug 28 16:44:00 volumio volumio[1167]: info: CorePlayQueue::addQueueItems Aug 28 16:44:00 volumio volumio[1167]: info: Preload queue cleared Aug 28 16:44:00 volumio volumio[1167]: info: Adding Item to queue: music-library/NAS/music/mike/A国语Hi-Res/姚斯婷&刘亮鹭 - 婷声鹭影 2023 Aug 28 16:44:00 volumio volumio[1167]: info: Exploding uri music-library/NAS/music/mike/A国语Hi-Res/姚斯婷&刘亮鹭 - 婷声鹭影 2023 in service mpd Aug 28 16:44:00 volumio volumio5-onboarding[1476]: time=2026-08-28T16:44:00.732+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 28 16:44:00 volumio volumio5-onboarding[1476]: time=2026-08-28T16:44:00.733+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.28.148:59004,00:00:00:00:00:00%02 @ 0x254a2d0" id="mnt/NAS/music/mike/A国语Hi-Res/刘锡珍 陌上初华/11. 鐵塔淩雲.wav" title="11. ?F???R??" Aug 28 16:44:00 volumio volumio[1167]: info: ALBUMART /albumart?cacheid=414&web=%3F%3F%3F%26%3F%3F%3F/%E5%A9%B7%E5%A3%B0%E9%B9%AD%E5%BD%B1/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E5%9B%BD%E8%AF%ADHi-Res%2F%E5%A7%9A%E6%96%AF%E5%A9%B7%26%E5%88%98%E4%BA%AE%E9%B9%AD%20-%20%E5%A9%B7%E5%A3%B0%E9%B9%AD%E5%BD%B1%202023%2F%E5%A7%9A%E6%96%AF%E5%A9%B7%26%E5%88%98%E4%BA%AE%E9%B9%AD%20-%20%E5%A9%B7%E5%A3%B0%E9%B9%AD%E5%BD%B1.cue&metadata=false Aug 28 16:44:00 volumio volumio[1167]: info: URI /mnt/NAS/music/mike/A国语Hi-Res/姚斯婷&刘亮鹭 - 婷声鹭影 2023/姚斯婷&刘亮鹭 - 婷声鹭影.cue Aug 28 16:44:00 volumio volumio[1167]: info: ALBUMART /albumart?cacheid=414&web=%3F%3F%3F%26%3F%3F%3F/%E5%A9%B7%E5%A3%B0%E9%B9%AD%E5%BD%B1/extralarge&path=%2Fmnt%2FNAS%2Fmusic%2Fmike%2FA%E5%9B%BD%E8%AF%ADHi-Res%2F%E5%A7%9A%E6%96%AF%E5%A9%B7%26%E5%88%98%E4%BA%AE%E9%B9%AD%20-%20%E5%A9%B7%E5%A3%B0%E9%B9%AD%E5%BD%B1%202023%2F%E5%A7%9A%E6%96%AF%E5%A9%B7%26%E5%88%98%E4%BA%AE%E9%B9%AD%20-%20%E5%A9%B7%E5%A3%B0%E9%B9%AD%E5%BD%B1.cue&metadata=false Aug 28 16:44:00 volumio volumio[1167]: info: URI /mnt/NAS/music/mike/A国语Hi-Res/姚斯婷&刘亮鹭 - 婷声鹭影 2023/姚斯婷&刘亮鹭 - 婷声鹭影.cue Aug 28 16:44:00 volumio volumio[1167]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:44:00 volumio volumio[1167]: Error: Unable to resolve or reject the same promise twice Aug 28 16:44:00 volumio volumio[1167]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 28 16:44:00 volumio volumio[1167]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 28 16:44:00 volumio volumio[1167]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 28 16:44:00 volumio volumio[1167]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 28 16:44:00 volumio volumio[1167]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 28 16:44:00 volumio volumio[1167]: at Socket.emit (node:events:514:28) Aug 28 16:44:00 volumio volumio[1167]: at addChunk (node:internal/streams/readable:343:12) Aug 28 16:44:00 volumio volumio[1167]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 28 16:44:00 volumio volumio[1167]: at Readable.push (node:internal/streams/readable:253:10) Aug 28 16:44:00 volumio volumio[1167]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 28 16:44:00 volumio volumio[1167]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:44:01 volumio sudo[10479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 16:43' Aug 28 16:44:01 volumio sudo[10479]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"