-- Logs begin at Mon 2026-01-12 09:11:55 CST, end at Mon 2026-01-12 13:36:49 CST. -- Jan 12 13:35:18 kx volumio[898]: info: CorePlayQueue::getTrack 1397 Jan 12 13:35:18 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:18 kx volumio[898]: info: Prefetching next song Jan 12 13:35:18 kx volumio[898]: info: DOING PREFETCH IN MPD Jan 12 13:35:18 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/普通无损/APE/中文/周华健 - 有故事的人.ape" Jan 12 13:35:18 kx volumio[898]: info: Jan 12 13:35:18 kx volumio[898]: ---------------------------- MPD announces system playlist update Jan 12 13:35:18 kx volumio[898]: info: Ignoring MPD Status Update Jan 12 13:35:18 kx volumio[898]: info: sendMpdCommand add "NAS/NAS/普通无损/APE/中文/周华健 - 有故事的人.ape" took 2 milliseconds Jan 12 13:35:18 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 12 13:35:18 kx volumio[898]: info: Jan 12 13:35:18 kx volumio[898]: ---------------------------- MPD announces system playlist update Jan 12 13:35:18 kx volumio[898]: info: Ignoring MPD Status Update Jan 12 13:35:18 kx volumio[898]: info: Jan 12 13:35:18 kx volumio[898]: ---------------------------- MPD announces system playlist update Jan 12 13:35:18 kx volumio[898]: info: Ignoring MPD Status Update Jan 12 13:35:18 kx volumio[898]: info: ------------------------------ 6ms Jan 12 13:35:18 kx volumio[898]: info: sendMpdCommand consume 1 took 4 milliseconds Jan 12 13:35:18 kx volumio[898]: info: ------------------------------ 4ms Jan 12 13:35:18 kx volumio[898]: info: ------------------------------ 3ms Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::startPlaybackTimer Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: Jan 12 13:35:23 kx volumio[898]: ---------------------------- MPD announces system playlist update Jan 12 13:35:23 kx volumio[898]: info: Ignoring MPD Status Update Jan 12 13:35:23 kx volumio[898]: info: Jan 12 13:35:23 kx volumio[898]: ---------------------------- MPD announces state update: player Jan 12 13:35:23 kx volumio[898]: info: ControllerMpd::getState Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand status Jan 12 13:35:23 kx volumio[898]: info: Jan 12 13:35:23 kx volumio[898]: ---------------------------- MPD announces system playlist update Jan 12 13:35:23 kx volumio[898]: info: Ignoring MPD Status Update Jan 12 13:35:23 kx volumio[898]: info: Jan 12 13:35:23 kx volumio[898]: ---------------------------- MPD announces state update: player Jan 12 13:35:23 kx volumio[898]: info: ControllerMpd::getState Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand status Jan 12 13:35:23 kx volumio[898]: info: Jan 12 13:35:23 kx volumio[898]: ---------------------------- MPD announces system playlist update Jan 12 13:35:23 kx volumio[898]: info: Ignoring MPD Status Update Jan 12 13:35:23 kx volumio[898]: info: Jan 12 13:35:23 kx volumio[898]: ---------------------------- MPD announces state update: player Jan 12 13:35:23 kx volumio[898]: info: ControllerMpd::getState Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand status Jan 12 13:35:23 kx volumio[898]: info: ------------------------------ 8ms Jan 12 13:35:23 kx volumio[898]: info: sendMpdCommand status took 6 milliseconds Jan 12 13:35:23 kx volumio[898]: info: ------------------------------ 6ms Jan 12 13:35:23 kx volumio[898]: info: sendMpdCommand status took 5 milliseconds Jan 12 13:35:23 kx volumio[898]: info: ------------------------------ 4ms Jan 12 13:35:23 kx volumio[898]: info: sendMpdCommand status took 2 milliseconds Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::parseState Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::parseState Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::parseState Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 13:35:23 kx volumio[898]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 13:35:23 kx volumio[898]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 13:35:23 kx volumio[898]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::parseTrackInfo Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::parseTrackInfo Jan 12 13:35:23 kx volumio[898]: verbose: ControllerMpd::parseTrackInfo Jan 12 13:35:23 kx volumio[898]: info: ControllerMpd::pushState Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::servicePushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"有故事的人","artist":"周华健","album":null,"uri":"NAS/NAS/普通无损/APE/中文/周华健 - 有故事的人.ape","trackType":"ape"} Jan 12 13:35:23 kx volumio[898]: verbose: CURRENT POSITION 178 Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::syncState stateService play Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::syncState currentStatus play Jan 12 13:35:23 kx volumio[898]: info: Received an update from plugin. extracting info from payload Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::pushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::volumioPushState Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::pushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::volumioPushState Jan 12 13:35:23 kx volumio[898]: info: ControllerMpd::pushState Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::servicePushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"有故事的人","artist":"周华健","album":null,"uri":"NAS/NAS/普通无损/APE/中文/周华健 - 有故事的人.ape","trackType":"ape"} Jan 12 13:35:23 kx volumio[898]: verbose: CURRENT POSITION 178 Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::syncState stateService play Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::syncState currentStatus play Jan 12 13:35:23 kx volumio[898]: info: Received an update from plugin. extracting info from payload Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::pushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::volumioPushState Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::pushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::volumioPushState Jan 12 13:35:23 kx volumio[898]: info: ControllerMpd::pushState Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::servicePushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"有故事的人","artist":"周华健","album":null,"uri":"NAS/NAS/普通无损/APE/中文/周华健 - 有故事的人.ape","trackType":"ape"} Jan 12 13:35:23 kx volumio[898]: verbose: CURRENT POSITION 178 Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::syncState stateService play Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::syncState currentStatus play Jan 12 13:35:23 kx volumio[898]: info: Received an update from plugin. extracting info from payload Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::pushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::volumioPushState Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::pushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::volumioPushState Jan 12 13:35:23 kx volumio[898]: info: ------------------------------ 70ms Jan 12 13:35:23 kx volumio[898]: info: ------------------------------ 67ms Jan 12 13:35:23 kx volumio[898]: info: ------------------------------ 66ms Jan 12 13:35:23 kx volumio[898]: info: CoreStateMachine::pushState Jan 12 13:35:23 kx volumio[898]: info: CorePlayQueue::getTrack 178 Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 13:35:23 kx volumio[898]: info: CoreCommandRouter::volumioPushState Jan 12 13:36:49 kx volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 13:36:49 kx volumio[898]: Error: connect ETIMEDOUT 108.160.169.46:443 Jan 12 13:36:49 kx volumio[898]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 12 13:36:49 kx volumio[898]: errno: -110, Jan 12 13:36:49 kx volumio[898]: code: 'ETIMEDOUT', Jan 12 13:36:49 kx volumio[898]: syscall: 'connect', Jan 12 13:36:49 kx volumio[898]: address: '108.160.169.46', Jan 12 13:36:49 kx volumio[898]: port: 443 Jan 12 13:36:49 kx volumio[898]: } Jan 12 13:36:49 kx volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 13:36:49 kx sudo[8000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 13:35 Jan 12 13:36:49 kx sudo[8000]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"