-- Logs begin at Thu 2025-01-16 08:10:05 CST, end at Thu 2025-01-16 09:08:16 CST. -- Jan 16 09:07:08 volumio volumio[7780]: info: CoreCommandRouter::volumioGetState Jan 16 09:07:08 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:07:19 volumio volumio[7780]: info: CoreCommandRouter::volumioGetState Jan 16 09:07:19 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:07:30 volumio volumio[7780]: info: CoreCommandRouter::volumioGetState Jan 16 09:07:30 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:07:41 volumio volumio[7780]: info: CoreCommandRouter::volumioGetState Jan 16 09:07:41 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:07:52 volumio volumio[7780]: info: CoreCommandRouter::volumioGetState Jan 16 09:07:52 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:03 volumio volumio[7780]: info: CoreCommandRouter::volumioGetState Jan 16 09:08:03 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 4 Jan 16 09:08:14 volumio volumio[7780]: info: Prefetching next song Jan 16 09:08:14 volumio volumio[7780]: info: DOING PREFETCH IN MPD Jan 16 09:08:14 volumio volumio[7780]: verbose: ControllerMpd::sendMpdCommand add "USB/46EA-FA68/陈慧娴/陈慧娴-情意结.flac" Jan 16 09:08:14 volumio volumio[7780]: info: Jan 16 09:08:14 volumio volumio[7780]: ---------------------------- MPD announces system playlist update Jan 16 09:08:14 volumio volumio[7780]: info: Ignoring MPD Status Update Jan 16 09:08:14 volumio volumio[7780]: info: sendMpdCommand add "USB/46EA-FA68/陈慧娴/陈慧娴-情意结.flac" took 2 milliseconds Jan 16 09:08:14 volumio volumio[7780]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 16 09:08:14 volumio volumio[7780]: info: Jan 16 09:08:14 volumio volumio[7780]: ---------------------------- MPD announces system playlist update Jan 16 09:08:14 volumio volumio[7780]: info: Ignoring MPD Status Update Jan 16 09:08:14 volumio volumio[7780]: info: Jan 16 09:08:14 volumio volumio[7780]: ---------------------------- MPD announces state update: options Jan 16 09:08:14 volumio volumio[7780]: info: ------------------------------ 3ms Jan 16 09:08:14 volumio volumio[7780]: info: sendMpdCommand consume 1 took 2 milliseconds Jan 16 09:08:14 volumio volumio[7780]: info: ControllerMpd::getState Jan 16 09:08:14 volumio volumio[7780]: verbose: ControllerMpd::sendMpdCommand status Jan 16 09:08:14 volumio volumio[7780]: info: Jan 16 09:08:14 volumio volumio[7780]: ---------------------------- MPD announces state update: options Jan 16 09:08:14 volumio volumio[7780]: info: ControllerMpd::getState Jan 16 09:08:14 volumio volumio[7780]: verbose: ControllerMpd::sendMpdCommand status Jan 16 09:08:14 volumio volumio[7780]: info: ------------------------------ 4ms Jan 16 09:08:14 volumio volumio[7780]: info: sendMpdCommand status took 24 milliseconds Jan 16 09:08:14 volumio volumio[7780]: info: sendMpdCommand status took 24 milliseconds Jan 16 09:08:14 volumio volumio[7780]: verbose: ControllerMpd::parseState Jan 16 09:08:14 volumio volumio[7780]: info: ControllerMpd::pushState Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::servicePushState Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::pushState Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::volumioPushState Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 09:08:14 volumio volumio[7780]: verbose: CURRENT POSITION 3 Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::syncState stateService stop Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::syncState currentStatus pause Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::pushState Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::volumioPushState Jan 16 09:08:14 volumio volumio[7780]: info: ControllerMpd::pushState Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::servicePushState Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::pushState Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::volumioPushState Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 09:08:14 volumio volumio[7780]: verbose: CURRENT POSITION 3 Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::syncState stateService stop Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::syncState currentStatus pause Jan 16 09:08:14 volumio volumio[7780]: info: CoreStateMachine::pushState Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::volumioPushState Jan 16 09:08:14 volumio volumio[7780]: info: ------------------------------ 58ms Jan 16 09:08:14 volumio volumio[7780]: info: ------------------------------ 56ms Jan 16 09:08:14 volumio volumio[7780]: info: CoreCommandRouter::volumioGetState Jan 16 09:08:14 volumio volumio[7780]: info: CorePlayQueue::getTrack 3 Jan 16 09:08:15 volumio volumio[7780]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 09:08:15 volumio volumio[7780]: Error: connect ETIMEDOUT 128.242.240.253:443 Jan 16 09:08:15 volumio volumio[7780]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 16 09:08:15 volumio volumio[7780]: errno: -110, Jan 16 09:08:15 volumio volumio[7780]: code: 'ETIMEDOUT', Jan 16 09:08:15 volumio volumio[7780]: syscall: 'connect', Jan 16 09:08:15 volumio volumio[7780]: address: '128.242.240.253', Jan 16 09:08:15 volumio volumio[7780]: port: 443 Jan 16 09:08:15 volumio volumio[7780]: } Jan 16 09:08:15 volumio volumio[7780]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 09:08:16 volumio sudo[11370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-16 09:07 Jan 16 09:08:16 volumio sudo[11370]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"