-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Tue 2025-12-09 21:50:23 CST. --
Dec 09 21:49:03 volumio volumio[16088]: info: UPDATER: Scheduling automatic update
Dec 09 21:49:03 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 09 21:49:03 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 09 21:49:03 volumio volumio[16088]: info: UPDATER: Auto update will take place at: Wed Dec 10 2025 04:33:11 GMT+0800 (China Standard Time)
Dec 09 21:49:23 volumio volumio-remote-updater[766]: No test mode
Dec 09 21:49:23 volumio volumio-remote-updater[766]: No alpha test mode
Dec 09 21:49:24 volumio volumio[16088]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for TIDAL Authentication refresh
\n
\nNEW ADDITIONS
\n\n- Update Bluez to 5.83
\n
\n","title":"Update v3.874","updateavailable":true}
Dec 09 21:49:24 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 09 21:49:27 volumio volumio[16088]: info: CoreCommandRouter::Close All Modals sent
Dec 09 21:49:29 volumio volumio[16088]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 09 21:49:29 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 09 21:49:30 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 09 21:49:30 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 09 21:49:30 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Dec 09 21:49:30 volumio volumio[16088]: info: Received Get System Version
Dec 09 21:49:30 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::volumioPause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::pause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::stPlaybackTimer
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::servicePause
Dec 09 21:49:48 volumio volumio[16088]: info: CorePlayQueue::getTrack 6
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::servicePause
Dec 09 21:49:48 volumio volumio[16088]: info: ControllerMpd::pause
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::sendMpdCommand pause
Dec 09 21:49:48 volumio volumio[16088]: info:
Dec 09 21:49:48 volumio volumio[16088]: ---------------------------- MPD announces state update: player
Dec 09 21:49:48 volumio volumio[16088]: info: sendMpdCommand pause took 2 milliseconds
Dec 09 21:49:48 volumio volumio[16088]: info: ControllerMpd::getState
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::sendMpdCommand status
Dec 09 21:49:48 volumio volumio[16088]: info:
Dec 09 21:49:48 volumio volumio[16088]: ---------------------------- MPD announces state update: player
Dec 09 21:49:48 volumio volumio[16088]: info: ControllerMpd::getState
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::sendMpdCommand status
Dec 09 21:49:48 volumio volumio[16088]: info:
Dec 09 21:49:48 volumio volumio[16088]: ---------------------------- MPD announces state update: player
Dec 09 21:49:48 volumio volumio[16088]: info: ControllerMpd::getState
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::sendMpdCommand status
Dec 09 21:49:48 volumio volumio[16088]: info: sendMpdCommand status took 1 milliseconds
Dec 09 21:49:48 volumio volumio[16088]: info: sendMpdCommand status took 1 milliseconds
Dec 09 21:49:48 volumio volumio[16088]: info: sendMpdCommand status took 0 milliseconds
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::parseState
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::parseState
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::parseState
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 09 21:49:48 volumio volumio[16088]: info: sendMpdCommand playlistinfo took 0 milliseconds
Dec 09 21:49:48 volumio volumio[16088]: info: sendMpdCommand playlistinfo took 0 milliseconds
Dec 09 21:49:48 volumio volumio[16088]: info: sendMpdCommand playlistinfo took 0 milliseconds
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::parseTrackInfo
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::parseTrackInfo
Dec 09 21:49:48 volumio volumio[16088]: verbose: ControllerMpd::parseTrackInfo
Dec 09 21:49:48 volumio volumio[16088]: info: ControllerMpd::pushState
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::servicePushState
Dec 09 21:49:48 volumio volumio[16088]: info: CorePlayQueue::getTrack 6
Dec 09 21:49:48 volumio volumio[16088]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":100685,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Black Or White","artist":"Michael Jackson","album":"King Of Pop CD1","uri":"NAS/NAS/MP3/artist/Michael Jackson/King Of Pop CD1/07 Black Or White.mp3","trackType":"mp3"}
Dec 09 21:49:48 volumio volumio[16088]: verbose: CURRENT POSITION 6
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::syncState stateService pause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::syncState currentStatus pause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::pushState
Dec 09 21:49:48 volumio volumio[16088]: info: CorePlayQueue::getTrack 6
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::volumioPushState
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::stPlaybackTimer
Dec 09 21:49:48 volumio volumio[16088]: info: ControllerMpd::pushState
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::servicePushState
Dec 09 21:49:48 volumio volumio[16088]: info: CorePlayQueue::getTrack 6
Dec 09 21:49:48 volumio volumio[16088]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":100685,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Black Or White","artist":"Michael Jackson","album":"King Of Pop CD1","uri":"NAS/NAS/MP3/artist/Michael Jackson/King Of Pop CD1/07 Black Or White.mp3","trackType":"mp3"}
Dec 09 21:49:48 volumio volumio[16088]: verbose: CURRENT POSITION 6
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::syncState stateService pause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::syncState currentStatus pause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::pushState
Dec 09 21:49:48 volumio volumio[16088]: info: CorePlayQueue::getTrack 6
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::volumioPushState
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::stPlaybackTimer
Dec 09 21:49:48 volumio volumio[16088]: info: ControllerMpd::pushState
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::servicePushState
Dec 09 21:49:48 volumio volumio[16088]: info: CorePlayQueue::getTrack 6
Dec 09 21:49:48 volumio volumio[16088]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":100685,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Black Or White","artist":"Michael Jackson","album":"King Of Pop CD1","uri":"NAS/NAS/MP3/artist/Michael Jackson/King Of Pop CD1/07 Black Or White.mp3","trackType":"mp3"}
Dec 09 21:49:48 volumio volumio[16088]: verbose: CURRENT POSITION 6
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::syncState stateService pause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::syncState currentStatus pause
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::pushState
Dec 09 21:49:48 volumio volumio[16088]: info: CorePlayQueue::getTrack 6
Dec 09 21:49:48 volumio volumio[16088]: info: CoreCommandRouter::volumioPushState
Dec 09 21:49:48 volumio volumio[16088]: info: CoreStateMachine::stPlaybackTimer
Dec 09 21:49:48 volumio volumio[16088]: info: ------------------------------ 15ms
Dec 09 21:49:48 volumio volumio[16088]: info: ------------------------------ 14ms
Dec 09 21:49:48 volumio volumio[16088]: info: ------------------------------ 15ms
Dec 09 21:50:23 volumio volumio[16088]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 21:50:23 volumio volumio[16088]: Error: connect ETIMEDOUT 162.125.8.1:443
Dec 09 21:50:23 volumio volumio[16088]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
Dec 09 21:50:23 volumio volumio[16088]: errno: -110,
Dec 09 21:50:23 volumio volumio[16088]: code: 'ETIMEDOUT',
Dec 09 21:50:23 volumio volumio[16088]: syscall: 'connect',
Dec 09 21:50:23 volumio volumio[16088]: address: '162.125.8.1',
Dec 09 21:50:23 volumio volumio[16088]: port: 443
Dec 09 21:50:23 volumio volumio[16088]: }
Dec 09 21:50:23 volumio volumio[16088]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 21:50:23 volumio sudo[16627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 21:49
Dec 09 21:50:23 volumio sudo[16627]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="29866754e5f1d7e4d0f581c10d9f22852f6f21db"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST"
VOLUMIO_VERSION="3.832"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"