-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Mon 2026-06-01 09:47:51 CST. -- Jun 01 09:46:25 volumio volumio[944]: error: error Jun 01 09:47:15 volumio volumio[944]: error: error Jun 01 09:47:27 volumio ntpd[765]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 01 09:47:37 volumio volumio[944]: verbose: New Socket.io Connection to 192.168.10.125 from 192.168.10.119 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 Jun 01 09:47:37 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Jun 01 09:47:37 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:37 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 09:47:37 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 09:47:40 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:40 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:40 volumio volumio[944]: info: Prefetching next song Jun 01 09:47:40 volumio volumio[944]: info: DOING PREFETCH IN MPD Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "USB/9EC9-57F3/月下思故人、红蔷薇 - 和我亲爱的在一起.flac" Jun 01 09:47:40 volumio volumio[944]: info: sendMpdCommand add "USB/9EC9-57F3/月下思故人、红蔷薇 - 和我亲爱的在一起.flac" took 1 milliseconds Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 01 09:47:40 volumio volumio[944]: info: Jun 01 09:47:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jun 01 09:47:40 volumio volumio[944]: info: Ignoring MPD Status Update Jun 01 09:47:40 volumio volumio[944]: info: Jun 01 09:47:40 volumio volumio[944]: ---------------------------- MPD announces state update: options Jun 01 09:47:40 volumio volumio[944]: info: sendMpdCommand consume 1 took 4 milliseconds Jun 01 09:47:40 volumio volumio[944]: info: ControllerMpd::getState Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:47:40 volumio volumio[944]: info: Jun 01 09:47:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jun 01 09:47:40 volumio volumio[944]: info: Ignoring MPD Status Update Jun 01 09:47:40 volumio volumio[944]: info: Jun 01 09:47:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jun 01 09:47:40 volumio volumio[944]: info: Ignoring MPD Status Update Jun 01 09:47:40 volumio volumio[944]: info: ------------------------------ 8ms Jun 01 09:47:40 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Jun 01 09:47:40 volumio volumio[944]: info: ------------------------------ 6ms Jun 01 09:47:40 volumio volumio[944]: info: ------------------------------ 5ms Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::parseState Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:47:40 volumio volumio[944]: info: Jun 01 09:47:40 volumio volumio[944]: ---------------------------- MPD announces state update: options Jun 01 09:47:40 volumio volumio[944]: info: ControllerMpd::getState Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:47:40 volumio volumio[944]: info: Jun 01 09:47:40 volumio volumio[944]: ---------------------------- MPD announces state update: options Jun 01 09:47:40 volumio volumio[944]: info: ControllerMpd::getState Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:47:40 volumio volumio[944]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 01 09:47:40 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::parseState Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:47:40 volumio volumio[944]: info: ControllerMpd::pushState Jun 01 09:47:40 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jun 01 09:47:40 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:40 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":253988,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"523 Kbps","isStreaming":false,"title":"听闻远方有你","artist":"刘艺雯","album":"听闻远方有你","uri":"USB/9EC9-57F3/刘艺雯 - 听闻远方有你.flac","trackType":"flac"} Jun 01 09:47:40 volumio volumio[944]: verbose: CURRENT POSITION 213 Jun 01 09:47:40 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jun 01 09:47:40 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:47:40 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jun 01 09:47:40 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:40 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:40 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 09:47:40 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:40 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:40 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:40 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:40 volumio volumio[944]: info: ------------------------------ 54ms Jun 01 09:47:40 volumio volumio[944]: info: sendMpdCommand status took 42 milliseconds Jun 01 09:47:40 volumio volumio[944]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::parseState Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:47:40 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:47:40 volumio volumio[944]: info: ControllerMpd::pushState Jun 01 09:47:40 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jun 01 09:47:40 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:40 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":253988,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"523 Kbps","isStreaming":false,"title":"听闻远方有你","artist":"刘艺雯","album":"听闻远方有你","uri":"USB/9EC9-57F3/刘艺雯 - 听闻远方有你.flac","trackType":"flac"} Jun 01 09:47:40 volumio volumio[944]: verbose: CURRENT POSITION 213 Jun 01 09:47:40 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jun 01 09:47:40 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:47:40 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jun 01 09:47:40 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:40 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:40 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:41 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:41 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:41 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:41 volumio volumio[944]: info: ------------------------------ 81ms Jun 01 09:47:41 volumio volumio[944]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 01 09:47:41 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:47:41 volumio volumio[944]: info: ControllerMpd::pushState Jun 01 09:47:41 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jun 01 09:47:41 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:41 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":253988,"duration":259,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"523 Kbps","isStreaming":false,"title":"听闻远方有你","artist":"刘艺雯","album":"听闻远方有你","uri":"USB/9EC9-57F3/刘艺雯 - 听闻远方有你.flac","trackType":"flac"} Jun 01 09:47:41 volumio volumio[944]: verbose: CURRENT POSITION 213 Jun 01 09:47:41 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jun 01 09:47:41 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:47:41 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jun 01 09:47:41 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:41 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:41 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:41 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:41 volumio volumio[944]: info: CorePlayQueue::getTrack 213 Jun 01 09:47:41 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:41 volumio volumio[944]: info: ------------------------------ 111ms Jun 01 09:47:45 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jun 01 09:47:45 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:45 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:45 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:45 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 09:47:45 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:46 volumio volumio[944]: info: Jun 01 09:47:46 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jun 01 09:47:46 volumio volumio[944]: info: Ignoring MPD Status Update Jun 01 09:47:46 volumio volumio[944]: info: Jun 01 09:47:46 volumio volumio[944]: ---------------------------- MPD announces state update: player Jun 01 09:47:46 volumio volumio[944]: info: ControllerMpd::getState Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:47:46 volumio volumio[944]: info: Jun 01 09:47:46 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jun 01 09:47:46 volumio volumio[944]: info: Ignoring MPD Status Update Jun 01 09:47:46 volumio volumio[944]: info: Jun 01 09:47:46 volumio volumio[944]: ---------------------------- MPD announces state update: player Jun 01 09:47:46 volumio volumio[944]: info: ControllerMpd::getState Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:47:46 volumio volumio[944]: info: Jun 01 09:47:46 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jun 01 09:47:46 volumio volumio[944]: info: Ignoring MPD Status Update Jun 01 09:47:46 volumio volumio[944]: info: Jun 01 09:47:46 volumio volumio[944]: ---------------------------- MPD announces state update: player Jun 01 09:47:46 volumio volumio[944]: info: ControllerMpd::getState Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:47:46 volumio volumio[944]: info: ------------------------------ 10ms Jun 01 09:47:46 volumio volumio[944]: info: sendMpdCommand status took 8 milliseconds Jun 01 09:47:46 volumio volumio[944]: info: ------------------------------ 8ms Jun 01 09:47:46 volumio volumio[944]: info: sendMpdCommand status took 6 milliseconds Jun 01 09:47:46 volumio volumio[944]: info: ------------------------------ 6ms Jun 01 09:47:46 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::parseState Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::parseState Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::parseState Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:47:46 volumio volumio[944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:47:46 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:47:46 volumio volumio[944]: info: ControllerMpd::pushState Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"894 Kbps","isStreaming":false,"title":"和我亲爱的在一起 (新版对唱)","artist":"月下思故人、红蔷薇","album":"和我亲爱的在一起（新版对唱）","uri":"USB/9EC9-57F3/月下思故人、红蔷薇 - 和我亲爱的在一起.flac","trackType":"flac"} Jun 01 09:47:46 volumio volumio[944]: verbose: CURRENT POSITION 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:47:46 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:46 volumio volumio[944]: info: ControllerMpd::pushState Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"894 Kbps","isStreaming":false,"title":"和我亲爱的在一起 (新版对唱)","artist":"月下思故人、红蔷薇","album":"和我亲爱的在一起（新版对唱）","uri":"USB/9EC9-57F3/月下思故人、红蔷薇 - 和我亲爱的在一起.flac","trackType":"flac"} Jun 01 09:47:46 volumio volumio[944]: verbose: CURRENT POSITION 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:47:46 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:46 volumio volumio[944]: info: ControllerMpd::pushState Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"894 Kbps","isStreaming":false,"title":"和我亲爱的在一起 (新版对唱)","artist":"月下思故人、红蔷薇","album":"和我亲爱的在一起（新版对唱）","uri":"USB/9EC9-57F3/月下思故人、红蔷薇 - 和我亲爱的在一起.flac","trackType":"flac"} Jun 01 09:47:46 volumio volumio[944]: verbose: CURRENT POSITION 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:47:46 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:46 volumio volumio[944]: info: CoreStateMachine::pushState Jun 01 09:47:46 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:46 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jun 01 09:47:46 volumio volumio[944]: info: ------------------------------ 103ms Jun 01 09:47:46 volumio volumio[944]: info: ------------------------------ 100ms Jun 01 09:47:46 volumio volumio[944]: info: ------------------------------ 99ms Jun 01 09:47:48 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 09:47:48 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 09:47:48 volumio volumio[944]: info: Discovery: Getting this device information Jun 01 09:47:48 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Jun 01 09:47:48 volumio volumio[944]: info: CorePlayQueue::getTrack 214 Jun 01 09:47:48 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 09:47:50 volumio volumio[944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 09:47:50 volumio volumio[944]: Error: connect ETIMEDOUT 103.56.16.112:443 Jun 01 09:47:50 volumio volumio[944]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 01 09:47:50 volumio volumio[944]: errno: -110, Jun 01 09:47:50 volumio volumio[944]: code: 'ETIMEDOUT', Jun 01 09:47:50 volumio volumio[944]: syscall: 'connect', Jun 01 09:47:50 volumio volumio[944]: address: '103.56.16.112', Jun 01 09:47:50 volumio volumio[944]: port: 443 Jun 01 09:47:50 volumio volumio[944]: } Jun 01 09:47:50 volumio volumio[944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 09:47:51 volumio sudo[2781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-01 09:46 Jun 01 09:47:51 volumio sudo[2781]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"