Dec 28 11:22:19 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Dec 28 11:22:19 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Dec 28 11:22:19 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Dec 28 11:22:19 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Dec 28 11:22:57 volumio volumio[5775]: info: MPD Database updated - AlbumList cache refreshed Dec 28 11:22:57 volumio volumio[5775]: info: Dec 28 11:22:57 volumio volumio[5775]: ---------------------------- MPD announces state update: database Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: Dec 28 11:22:57 volumio volumio[5775]: ---------------------------- MPD announces state update: update Dec 28 11:22:57 volumio volumio[5775]: info: ControllerMpd::getState Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: ControllerMpd::getState Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: MPD Database updated - AlbumList cache refreshed Dec 28 11:22:57 volumio volumio[5775]: info: Dec 28 11:22:57 volumio volumio[5775]: ---------------------------- MPD announces state update: database Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: Dec 28 11:22:57 volumio volumio[5775]: ---------------------------- MPD announces state update: update Dec 28 11:22:57 volumio volumio[5775]: info: ControllerMpd::getState Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: ControllerMpd::getState Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: MPD Database updated - AlbumList cache refreshed Dec 28 11:22:57 volumio volumio[5775]: info: Dec 28 11:22:57 volumio volumio[5775]: ---------------------------- MPD announces state update: database Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: Dec 28 11:22:57 volumio volumio[5775]: ---------------------------- MPD announces state update: update Dec 28 11:22:57 volumio volumio[5775]: info: ControllerMpd::getState Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: info: ControllerMpd::getState Dec 28 11:22:57 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand status Dec 28 11:22:57 volumio volumio[5775]: Cannot compose Albumart path Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 175 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 175 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 174 milliseconds Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: info: Command Router : Notfying DB Updatefalse Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::Close All Modals sent Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 11:22:58 volumio volumio[5775]: Cannot compose Albumart path Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 304 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 304 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 305 milliseconds Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: info: Command Router : Notfying DB Updatefalse Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::Close All Modals sent Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 11:22:58 volumio volumio[5775]: Cannot compose Albumart path Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 423 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 423 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand status took 423 milliseconds Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: info: Command Router : Notfying DB Updatefalse Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::Close All Modals sent Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseState Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand playlistinfo took 253 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand playlistinfo took 253 milliseconds Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseTrackInfo Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseTrackInfo Dec 28 11:22:58 volumio volumio[5775]: info: ControllerMpd::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::servicePushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":65374,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6408 Kbps","isStreaming":false,"title":"驼铃","artist":"刀郎","album":"2002年的第一场雪","uri":"NAS/fn-OS/0014/驼铃 - 刀郎.flac","trackType":"flac"} Dec 28 11:22:58 volumio volumio[5775]: verbose: CURRENT POSITION 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState stateService pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState currentStatus pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::volumioPushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::stPlaybackTimer Dec 28 11:22:58 volumio volumio[5775]: info: ControllerMpd::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::servicePushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":65374,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6408 Kbps","isStreaming":false,"title":"驼铃","artist":"刀郎","album":"2002年的第一场雪","uri":"NAS/fn-OS/0014/驼铃 - 刀郎.flac","trackType":"flac"} Dec 28 11:22:58 volumio volumio[5775]: verbose: CURRENT POSITION 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState stateService pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState currentStatus pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::volumioPushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::stPlaybackTimer Dec 28 11:22:58 volumio volumio[5775]: info: ------------------------------ 474ms Dec 28 11:22:58 volumio volumio[5775]: info: ------------------------------ 474ms Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand playlistinfo took 132 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand playlistinfo took 132 milliseconds Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseTrackInfo Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseTrackInfo Dec 28 11:22:58 volumio volumio[5775]: info: ControllerMpd::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::servicePushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":65374,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6408 Kbps","isStreaming":false,"title":"驼铃","artist":"刀郎","album":"2002年的第一场雪","uri":"NAS/fn-OS/0014/驼铃 - 刀郎.flac","trackType":"flac"} Dec 28 11:22:58 volumio volumio[5775]: verbose: CURRENT POSITION 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState stateService pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState currentStatus pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::volumioPushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::stPlaybackTimer Dec 28 11:22:58 volumio volumio[5775]: info: ControllerMpd::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::servicePushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":65374,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6408 Kbps","isStreaming":false,"title":"驼铃","artist":"刀郎","album":"2002年的第一场雪","uri":"NAS/fn-OS/0014/驼铃 - 刀郎.flac","trackType":"flac"} Dec 28 11:22:58 volumio volumio[5775]: verbose: CURRENT POSITION 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState stateService pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState currentStatus pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::volumioPushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::stPlaybackTimer Dec 28 11:22:58 volumio volumio[5775]: info: ------------------------------ 482ms Dec 28 11:22:58 volumio volumio[5775]: info: ------------------------------ 482ms Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 28 11:22:58 volumio volumio[5775]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseTrackInfo Dec 28 11:22:58 volumio volumio[5775]: verbose: ControllerMpd::parseTrackInfo Dec 28 11:22:58 volumio volumio[5775]: info: ControllerMpd::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::servicePushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":65374,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6408 Kbps","isStreaming":false,"title":"驼铃","artist":"刀郎","album":"2002年的第一场雪","uri":"NAS/fn-OS/0014/驼铃 - 刀郎.flac","trackType":"flac"} Dec 28 11:22:58 volumio volumio[5775]: verbose: CURRENT POSITION 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState stateService pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState currentStatus pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::volumioPushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::stPlaybackTimer Dec 28 11:22:58 volumio volumio[5775]: info: ControllerMpd::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::servicePushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":65374,"duration":283,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6408 Kbps","isStreaming":false,"title":"驼铃","artist":"刀郎","album":"2002年的第一场雪","uri":"NAS/fn-OS/0014/驼铃 - 刀郎.flac","trackType":"flac"} Dec 28 11:22:58 volumio volumio[5775]: verbose: CURRENT POSITION 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState stateService pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::syncState currentStatus pause Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::pushState Dec 28 11:22:58 volumio volumio[5775]: info: CorePlayQueue::getTrack 3 Dec 28 11:22:58 volumio volumio[5775]: info: CoreCommandRouter::volumioPushState Dec 28 11:22:58 volumio volumio[5775]: info: CoreStateMachine::stPlaybackTimer Dec 28 11:22:58 volumio volumio[5775]: info: ------------------------------ 490ms Dec 28 11:22:58 volumio volumio[5775]: info: ------------------------------ 489ms Dec 28 11:23:03 volumio volumio[5775]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 11:23:03 volumio volumio[5775]: Error: connect ETIMEDOUT 128.242.240.218:443 Dec 28 11:23:03 volumio volumio[5775]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 28 11:23:03 volumio volumio[5775]: errno: -110, Dec 28 11:23:03 volumio volumio[5775]: code: 'ETIMEDOUT', Dec 28 11:23:03 volumio volumio[5775]: syscall: 'connect', Dec 28 11:23:03 volumio volumio[5775]: address: '128.242.240.218', Dec 28 11:23:03 volumio volumio[5775]: port: 443 Dec 28 11:23:03 volumio volumio[5775]: } Dec 28 11:23:03 volumio volumio[5775]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 11:23:03 volumio sudo[6693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 11:22' Dec 28 11:23:03 volumio sudo[6693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"