-- Logs begin at Sun 2025-05-18 05:23:05 UTC, end at Sun 2025-05-18 07:31:56 UTC. -- May 18 07:30:04 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 07:30:04 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 07:30:04 volumio volumio[930]: info: Discovery: Getting this device information May 18 07:30:04 volumio volumio[930]: info: CoreCommandRouter::volumioGetState May 18 07:30:04 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:04 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 07:30:27 volumio volumio[930]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found May 18 07:30:27 volumio volumio[930]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) May 18 07:30:27 volumio volumio[930]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) May 18 07:30:27 volumio volumio[930]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) May 18 07:30:27 volumio volumio[930]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) May 18 07:30:27 volumio volumio[930]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:440:9) May 18 07:30:27 volumio volumio[930]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 18 07:30:27 volumio volumio[930]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 18 07:30:34 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 07:30:34 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 07:30:34 volumio volumio[930]: info: Discovery: Getting this device information May 18 07:30:34 volumio volumio[930]: info: CoreCommandRouter::volumioGetState May 18 07:30:34 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:34 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 07:30:36 volumio volumio[930]: info: Preload queue cleared May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::volumioReplaceandPlayItems May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::ClearQueue May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::stop May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::clearPlayQueue May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::saveQueue May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::addQueueItems May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::addQueueItems May 18 07:30:36 volumio volumio[930]: info: Preload queue cleared May 18 07:30:36 volumio volumio[930]: info: Adding Item to queue: albums://%E6%96%B9%20%E6%96%87%20/%E6%83%85%EF%BC%8E%E6%87%B7%20%3A%20%E6%96%B9%E6%96%87%EF%BC%8E%E5%90%91%E9%9B%AA%E6%87%B7%20%20(%E9%8C%84%E9%9F%B3%E5%AE%A4%E6%AF%8D%E5%B8%B624%2F96) May 18 07:30:36 volumio volumio[930]: info: Exploding uri albums://%E6%96%B9%20%E6%96%87%20/%E6%83%85%EF%BC%8E%E6%87%B7%20%3A%20%E6%96%B9%E6%96%87%EF%BC%8E%E5%90%91%E9%9B%AA%E6%87%B7%20%20(%E9%8C%84%E9%9F%B3%E5%AE%A4%E6%AF%8D%E5%B8%B624%2F96) in service mpd May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::volumioPushQueue May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::saveQueue May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::updateTrackBlock May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::getTrackBlock May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::volumioPlay May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::play index 0 May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::stop May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::play index undefined May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::startPlaybackTimer May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐/24bit/24bit - 华语/Astor Fong 方 文 - 情．懷 方文．向雪懷 (錄音室母帶2496)/-01 藍月亮-方 文(FLAC 24_96).flac May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand stop May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand stop took 1 milliseconds May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand clear May 18 07:30:36 volumio volumio[930]: info: May 18 07:30:36 volumio volumio[930]: ---------------------------- MPD announces system playlist update May 18 07:30:36 volumio volumio[930]: info: Ignoring MPD Status Update May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand clear took 1 milliseconds May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐/24bit/24bit - 华语/Astor Fong 方 文 - 情．懷 方文．向雪懷 (錄音室母帶2496)/-01 藍月亮-方 文(FLAC 24_96).flac" May 18 07:30:36 volumio volumio[930]: error: updateQueue error: null May 18 07:30:36 volumio volumio[930]: info: May 18 07:30:36 volumio volumio[930]: ---------------------------- MPD announces system playlist update May 18 07:30:36 volumio volumio[930]: info: Ignoring MPD Status Update May 18 07:30:36 volumio volumio[930]: info: ------------------------------ 4ms May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand add "USB/音乐/24bit/24bit - 华语/Astor Fong 方 文 - 情．懷 方文．向雪懷 (錄音室母帶2496)/-01 藍月亮-方 文(FLAC 24_96).flac" took 3 milliseconds May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand play May 18 07:30:36 volumio volumio[930]: info: ------------------------------ 3ms May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand play took 3 milliseconds May 18 07:30:36 volumio volumio[930]: error: Upnp client error: Error: This socket has been ended by the other party May 18 07:30:36 volumio volumio[930]: info: May 18 07:30:36 volumio volumio[930]: ---------------------------- MPD announces state update: player May 18 07:30:36 volumio volumio[930]: info: ControllerMpd::getState May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand status took 4 milliseconds May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::parseState May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 07:30:36 volumio volumio[930]: info: May 18 07:30:36 volumio volumio[930]: ---------------------------- MPD announces state update: player May 18 07:30:36 volumio volumio[930]: info: ControllerMpd::getState May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand status May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand playlistinfo took 1 milliseconds May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand status took 0 milliseconds May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::parseState May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 07:30:36 volumio volumio[930]: info: ControllerMpd::pushState May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::servicePushState May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:36 volumio volumio[930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":321,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"藍月亮 ","artist":"方 文 ","album":"情．懷 : 方文．向雪懷 (錄音室母帶24/96)","uri":"USB/音乐/24bit/24bit - 华语/Astor Fong 方 文 - 情．懷 方文．向雪懷 (錄音室母帶2496)/-01 藍月亮-方 文(FLAC 24_96).flac","trackType":"flac"} May 18 07:30:36 volumio volumio[930]: verbose: CURRENT POSITION 0 May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::syncState stateService play May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus stop May 18 07:30:36 volumio volumio[930]: info: ------------------------------ 10ms May 18 07:30:36 volumio volumio[930]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 07:30:36 volumio volumio[930]: verbose: ControllerMpd::parseTrackInfo May 18 07:30:36 volumio volumio[930]: info: ControllerMpd::pushState May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::servicePushState May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:36 volumio volumio[930]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":321,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"藍月亮 ","artist":"方 文 ","album":"情．懷 : 方文．向雪懷 (錄音室母帶24/96)","uri":"USB/音乐/24bit/24bit - 华语/Astor Fong 方 文 - 情．懷 方文．向雪懷 (錄音室母帶2496)/-01 藍月亮-方 文(FLAC 24_96).flac","trackType":"flac"} May 18 07:30:36 volumio volumio[930]: verbose: CURRENT POSITION 0 May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::syncState stateService play May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::syncState currentStatus play May 18 07:30:36 volumio volumio[930]: info: Received an update from plugin. extracting info from payload May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::pushState May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::volumioPushState May 18 07:30:36 volumio volumio[930]: info: CoreStateMachine::pushState May 18 07:30:36 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:30:36 volumio volumio[930]: info: CoreCommandRouter::volumioPushState May 18 07:30:36 volumio volumio[930]: info: ------------------------------ 12ms May 18 07:31:04 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 07:31:04 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 07:31:04 volumio volumio[930]: info: Discovery: Getting this device information May 18 07:31:04 volumio volumio[930]: info: CoreCommandRouter::volumioGetState May 18 07:31:04 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:31:04 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 07:31:34 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 07:31:34 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 07:31:34 volumio volumio[930]: info: Discovery: Getting this device information May 18 07:31:34 volumio volumio[930]: info: CoreCommandRouter::volumioGetState May 18 07:31:34 volumio volumio[930]: info: CorePlayQueue::getTrack 0 May 18 07:31:34 volumio volumio[930]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 07:31:56 volumio volumio[930]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 07:31:56 volumio volumio[930]: Error: connect ETIMEDOUT 128.242.240.244:80 May 18 07:31:56 volumio volumio[930]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 18 07:31:56 volumio volumio[930]: errno: -110, May 18 07:31:56 volumio volumio[930]: code: 'ETIMEDOUT', May 18 07:31:56 volumio volumio[930]: syscall: 'connect', May 18 07:31:56 volumio volumio[930]: address: '128.242.240.244', May 18 07:31:56 volumio volumio[930]: port: 80 May 18 07:31:56 volumio volumio[930]: } May 18 07:31:56 volumio volumio[930]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 07:31:56 volumio sudo[11568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 07:30 May 18 07:31:56 volumio sudo[11568]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 06:04:32 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dd58c1c2d5a97e584de8089531ce548f"