-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-11-03 01:21:51 CST. -- Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:45 volumio volumio[17584]: info: Prefetching next song Nov 03 01:20:45 volumio volumio[17584]: info: DOING PREFETCH IN MPD Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/02. 梧桐相思雨.flac" Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand add "NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/02. 梧桐相思雨.flac" took 53 milliseconds Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 03 01:20:45 volumio volumio[17584]: info: Nov 03 01:20:45 volumio volumio[17584]: ---------------------------- MPD announces system playlist update Nov 03 01:20:45 volumio volumio[17584]: info: Ignoring MPD Status Update Nov 03 01:20:45 volumio volumio[17584]: info: Nov 03 01:20:45 volumio volumio[17584]: ---------------------------- MPD announces state update: options Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand consume 1 took 3 milliseconds Nov 03 01:20:45 volumio volumio[17584]: info: ControllerMpd::getState Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand status Nov 03 01:20:45 volumio volumio[17584]: info: Nov 03 01:20:45 volumio volumio[17584]: ---------------------------- MPD announces system playlist update Nov 03 01:20:45 volumio volumio[17584]: info: Ignoring MPD Status Update Nov 03 01:20:45 volumio volumio[17584]: info: Nov 03 01:20:45 volumio volumio[17584]: ---------------------------- MPD announces system playlist update Nov 03 01:20:45 volumio volumio[17584]: info: Ignoring MPD Status Update Nov 03 01:20:45 volumio volumio[17584]: info: ------------------------------ 53ms Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand status took 54 milliseconds Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::parseState Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 01:20:45 volumio volumio[17584]: info: ------------------------------ 56ms Nov 03 01:20:45 volumio volumio[17584]: info: ------------------------------ 55ms Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::parseTrackInfo Nov 03 01:20:45 volumio volumio[17584]: info: ControllerMpd::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::servicePushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: verbose: STATE SERVICE {"status":"play","position":0,"seek":279065,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"119 Kbps","isStreaming":false,"title":"爱情啊！真那个悲","artist":"姚璎格","album":"心在哭泣","uri":"NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/01. 爱情啊！真那个悲.flac","trackType":"flac"} Nov 03 01:20:45 volumio volumio[17584]: verbose: CURRENT POSITION 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::syncState stateService play Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::syncState currentStatus play Nov 03 01:20:45 volumio volumio[17584]: info: Received an update from plugin. extracting info from payload Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:45 volumio volumio[17584]: info: ------------------------------ 109ms Nov 03 01:20:45 volumio volumio[17584]: info: Nov 03 01:20:45 volumio volumio[17584]: ---------------------------- MPD announces state update: options Nov 03 01:20:45 volumio volumio[17584]: info: ControllerMpd::getState Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand status Nov 03 01:20:45 volumio volumio[17584]: info: Nov 03 01:20:45 volumio volumio[17584]: ---------------------------- MPD announces state update: options Nov 03 01:20:45 volumio volumio[17584]: info: ControllerMpd::getState Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand status Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand status took 5 milliseconds Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand status took 3 milliseconds Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::parseState Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::parseState Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand playlistinfo took 26 milliseconds Nov 03 01:20:45 volumio volumio[17584]: info: sendMpdCommand playlistinfo took 26 milliseconds Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::parseTrackInfo Nov 03 01:20:45 volumio volumio[17584]: verbose: ControllerMpd::parseTrackInfo Nov 03 01:20:45 volumio volumio[17584]: info: ControllerMpd::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::servicePushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: verbose: STATE SERVICE {"status":"play","position":0,"seek":279156,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"爱情啊！真那个悲","artist":"姚璎格","album":"心在哭泣","uri":"NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/01. 爱情啊！真那个悲.flac","trackType":"flac"} Nov 03 01:20:45 volumio volumio[17584]: verbose: CURRENT POSITION 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::syncState stateService play Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::syncState currentStatus play Nov 03 01:20:45 volumio volumio[17584]: info: Received an update from plugin. extracting info from payload Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:45 volumio volumio[17584]: info: ControllerMpd::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::servicePushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: verbose: STATE SERVICE {"status":"play","position":0,"seek":279156,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"爱情啊！真那个悲","artist":"姚璎格","album":"心在哭泣","uri":"NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/01. 爱情啊！真那个悲.flac","trackType":"flac"} Nov 03 01:20:45 volumio volumio[17584]: verbose: CURRENT POSITION 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::syncState stateService play Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::syncState currentStatus play Nov 03 01:20:45 volumio volumio[17584]: info: Received an update from plugin. extracting info from payload Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:45 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:45 volumio volumio[17584]: info: CorePlayQueue::getTrack 0 Nov 03 01:20:45 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:45 volumio volumio[17584]: info: ------------------------------ 112ms Nov 03 01:20:45 volumio volumio[17584]: info: ------------------------------ 110ms Nov 03 01:20:49 volumio volumio[17584]: info: CoreStateMachine::startPlaybackTimer Nov 03 01:20:49 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:49 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:49 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:49 volumio volumio[17584]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 01:20:49 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:50 volumio volumio[17584]: info: Nov 03 01:20:50 volumio volumio[17584]: ---------------------------- MPD announces system playlist update Nov 03 01:20:50 volumio volumio[17584]: info: Ignoring MPD Status Update Nov 03 01:20:50 volumio volumio[17584]: info: Nov 03 01:20:50 volumio volumio[17584]: ---------------------------- MPD announces state update: player Nov 03 01:20:50 volumio volumio[17584]: info: ControllerMpd::getState Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand status Nov 03 01:20:50 volumio volumio[17584]: info: Nov 03 01:20:50 volumio volumio[17584]: ---------------------------- MPD announces system playlist update Nov 03 01:20:50 volumio volumio[17584]: info: Ignoring MPD Status Update Nov 03 01:20:50 volumio volumio[17584]: info: Nov 03 01:20:50 volumio volumio[17584]: ---------------------------- MPD announces state update: player Nov 03 01:20:50 volumio volumio[17584]: info: ControllerMpd::getState Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand status Nov 03 01:20:50 volumio volumio[17584]: info: Nov 03 01:20:50 volumio volumio[17584]: ---------------------------- MPD announces system playlist update Nov 03 01:20:50 volumio volumio[17584]: info: Ignoring MPD Status Update Nov 03 01:20:50 volumio volumio[17584]: info: Nov 03 01:20:50 volumio volumio[17584]: ---------------------------- MPD announces state update: player Nov 03 01:20:50 volumio volumio[17584]: info: ControllerMpd::getState Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand status Nov 03 01:20:50 volumio volumio[17584]: info: ------------------------------ 10ms Nov 03 01:20:50 volumio volumio[17584]: info: sendMpdCommand status took 8 milliseconds Nov 03 01:20:50 volumio volumio[17584]: info: ------------------------------ 8ms Nov 03 01:20:50 volumio volumio[17584]: info: sendMpdCommand status took 6 milliseconds Nov 03 01:20:50 volumio volumio[17584]: info: ------------------------------ 5ms Nov 03 01:20:50 volumio volumio[17584]: info: sendMpdCommand status took 4 milliseconds Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::parseState Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::parseState Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::parseState Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 01:20:50 volumio volumio[17584]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 01:20:50 volumio volumio[17584]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 03 01:20:50 volumio volumio[17584]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::parseTrackInfo Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::parseTrackInfo Nov 03 01:20:50 volumio volumio[17584]: verbose: ControllerMpd::parseTrackInfo Nov 03 01:20:50 volumio volumio[17584]: info: ControllerMpd::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::servicePushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"485 Kbps","isStreaming":false,"title":"梧桐相思雨","artist":"姚璎格","album":"心在哭泣","uri":"NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/02. 梧桐相思雨.flac","trackType":"flac"} Nov 03 01:20:50 volumio volumio[17584]: verbose: CURRENT POSITION 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::syncState stateService play Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::syncState currentStatus play Nov 03 01:20:50 volumio volumio[17584]: info: Received an update from plugin. extracting info from payload Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:50 volumio volumio[17584]: info: ControllerMpd::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::servicePushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"485 Kbps","isStreaming":false,"title":"梧桐相思雨","artist":"姚璎格","album":"心在哭泣","uri":"NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/02. 梧桐相思雨.flac","trackType":"flac"} Nov 03 01:20:50 volumio volumio[17584]: verbose: CURRENT POSITION 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::syncState stateService play Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::syncState currentStatus play Nov 03 01:20:50 volumio volumio[17584]: info: Received an update from plugin. extracting info from payload Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:50 volumio volumio[17584]: info: ControllerMpd::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::servicePushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"485 Kbps","isStreaming":false,"title":"梧桐相思雨","artist":"姚璎格","album":"心在哭泣","uri":"NAS/music/人声2/姚璎格全集/姚璎格 2005《心在哭泣》龙韵 [FLAC分轨]/02. 梧桐相思雨.flac","trackType":"flac"} Nov 03 01:20:50 volumio volumio[17584]: verbose: CURRENT POSITION 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::syncState stateService play Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::syncState currentStatus play Nov 03 01:20:50 volumio volumio[17584]: info: Received an update from plugin. extracting info from payload Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:50 volumio volumio[17584]: info: CoreStateMachine::pushState Nov 03 01:20:50 volumio volumio[17584]: info: CorePlayQueue::getTrack 1 Nov 03 01:20:50 volumio volumio[17584]: info: CoreCommandRouter::volumioPushState Nov 03 01:20:50 volumio volumio[17584]: info: ------------------------------ 151ms Nov 03 01:20:50 volumio volumio[17584]: info: ------------------------------ 149ms Nov 03 01:20:50 volumio volumio[17584]: info: ------------------------------ 148ms Nov 03 01:21:49 volumio volumio[17584]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 01:21:49 volumio volumio[17584]: Error: connect ETIMEDOUT 199.16.158.190:443 Nov 03 01:21:49 volumio volumio[17584]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 03 01:21:49 volumio volumio[17584]: errno: -110, Nov 03 01:21:49 volumio volumio[17584]: code: 'ETIMEDOUT', Nov 03 01:21:49 volumio volumio[17584]: syscall: 'connect', Nov 03 01:21:49 volumio volumio[17584]: address: '199.16.158.190', Nov 03 01:21:49 volumio volumio[17584]: port: 443 Nov 03 01:21:49 volumio volumio[17584]: } Nov 03 01:21:49 volumio volumio[17584]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 01:21:51 volumio sudo[19165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 01:20 Nov 03 01:21:51 volumio sudo[19165]: 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"