-- Logs begin at Sun 2025-03-09 13:58:56 CST, end at Sun 2025-03-09 14:52:43 CST. -- Mar 09 14:51:07 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 09 14:51:07 volumio volumio[917]: info: CURURI: music-library Mar 09 14:51:07 volumio volumio[917]: info: Preload queue cleared Mar 09 14:51:09 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 09 14:51:09 volumio volumio[917]: info: CURURI: music-library/INTERNAL Mar 09 14:51:09 volumio volumio[917]: info: Preload queue cleared Mar 09 14:51:44 volumio volumio[917]: info: CoreCommandRouter::volumioMoveQueue Mar 09 14:51:44 volumio volumio[917]: info: CoreStateMachine::moveQueueItem 47 46 Mar 09 14:51:44 volumio volumio[917]: info: CorePlayQueue::moveQueueItem 47 --> 46 Mar 09 14:51:44 volumio volumio[917]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:51:46 volumio volumio[917]: info: CoreCommandRouter::volumioMoveQueue Mar 09 14:51:46 volumio volumio[917]: info: CoreStateMachine::moveQueueItem 38 38 Mar 09 14:51:46 volumio volumio[917]: info: CorePlayQueue::moveQueueItem 38 --> 38 Mar 09 14:51:46 volumio volumio[917]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 09 14:51:59 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 09 14:52:27 volumio volumio[917]: info: CorePlayQueue::getTrack 34 Mar 09 14:52:27 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:27 volumio volumio[917]: info: Prefetching next song Mar 09 14:52:27 volumio volumio[917]: info: DOING PREFETCH IN MPD Mar 09 14:52:27 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/高雅~メインテーマ.wav" Mar 09 14:52:27 volumio volumio[917]: info: Mar 09 14:52:27 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 09 14:52:27 volumio volumio[917]: info: Ignoring MPD Status Update Mar 09 14:52:27 volumio volumio[917]: info: sendMpdCommand add "INTERNAL/高雅~メインテーマ.wav" took 2 milliseconds Mar 09 14:52:27 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 09 14:52:27 volumio volumio[917]: info: Mar 09 14:52:27 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 09 14:52:27 volumio volumio[917]: info: Ignoring MPD Status Update Mar 09 14:52:27 volumio volumio[917]: info: ------------------------------ 3ms Mar 09 14:52:27 volumio volumio[917]: info: sendMpdCommand consume 1 took 2 milliseconds Mar 09 14:52:27 volumio volumio[917]: info: ------------------------------ 1ms Mar 09 14:52:31 volumio volumio[917]: info: CoreStateMachine::startPlaybackTimer Mar 09 14:52:31 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:32 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:32 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:32 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:52:32 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:32 volumio volumio[917]: info: Mar 09 14:52:32 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 09 14:52:32 volumio volumio[917]: info: Ignoring MPD Status Update Mar 09 14:52:32 volumio volumio[917]: info: Mar 09 14:52:32 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 09 14:52:32 volumio volumio[917]: info: ControllerMpd::getState Mar 09 14:52:32 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:52:32 volumio volumio[917]: info: Mar 09 14:52:32 volumio volumio[917]: ---------------------------- MPD announces system playlist update Mar 09 14:52:32 volumio volumio[917]: info: Ignoring MPD Status Update Mar 09 14:52:32 volumio volumio[917]: info: Mar 09 14:52:32 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 09 14:52:32 volumio volumio[917]: info: ControllerMpd::getState Mar 09 14:52:32 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:52:32 volumio volumio[917]: info: ------------------------------ 3ms Mar 09 14:52:33 volumio kernel: xhci_hcd 0000:04:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 3 Mar 09 14:52:33 volumio kernel: xhci_hcd 0000:04:00.0: @0000000101135c00 00000000 00000000 0e000000 02048000 Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand status took 1017 milliseconds Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:52:33 volumio volumio[917]: info: Mar 09 14:52:33 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 09 14:52:33 volumio volumio[917]: info: ControllerMpd::getState Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:52:33 volumio volumio[917]: info: ------------------------------ 1018ms Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand status took 1019 milliseconds Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:52:33 volumio volumio[917]: info: ControllerMpd::pushState Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":149,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??~??????","artist":"S.E.N.S.","album":"TV???\"??????? Genji\"?????????????","uri":"INTERNAL/高雅~メインテーマ.wav","trackType":"wav"} Mar 09 14:52:33 volumio volumio[917]: verbose: CURRENT POSITION 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:52:33 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: ------------------------------ 1036ms Mar 09 14:52:33 volumio volumio[917]: info: Mar 09 14:52:33 volumio volumio[917]: ---------------------------- MPD announces state update: player Mar 09 14:52:33 volumio volumio[917]: info: ControllerMpd::getState Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand status took 19 milliseconds Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:52:33 volumio volumio[917]: info: ControllerMpd::pushState Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":149,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??~??????","artist":"S.E.N.S.","album":"TV???\"??????? Genji\"?????????????","uri":"INTERNAL/高雅~メインテーマ.wav","trackType":"wav"} Mar 09 14:52:33 volumio volumio[917]: verbose: CURRENT POSITION 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:52:33 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: ------------------------------ 1052ms Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand status took 20 milliseconds Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseState Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:52:33 volumio volumio[917]: info: ControllerMpd::pushState Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":149,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??~??????","artist":"S.E.N.S.","album":"TV???\"??????? Genji\"?????????????","uri":"INTERNAL/高雅~メインテーマ.wav","trackType":"wav"} Mar 09 14:52:33 volumio volumio[917]: verbose: CURRENT POSITION 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:52:33 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: ------------------------------ 51ms Mar 09 14:52:33 volumio volumio[917]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 09 14:52:33 volumio volumio[917]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:52:33 volumio volumio[917]: info: ControllerMpd::pushState Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::servicePushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":149,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??~??????","artist":"S.E.N.S.","album":"TV???\"??????? Genji\"?????????????","uri":"INTERNAL/高雅~メインテーマ.wav","trackType":"wav"} Mar 09 14:52:33 volumio volumio[917]: verbose: CURRENT POSITION 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState stateService play Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:52:33 volumio volumio[917]: info: Received an update from plugin. extracting info from payload Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: CoreStateMachine::pushState Mar 09 14:52:33 volumio volumio[917]: info: CorePlayQueue::getTrack 35 Mar 09 14:52:33 volumio volumio[917]: info: CoreCommandRouter::volumioPushState Mar 09 14:52:33 volumio volumio[917]: info: ------------------------------ 47ms Mar 09 14:52:43 volumio volumio[917]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 14:52:43 volumio volumio[917]: Error: connect ETIMEDOUT 168.143.171.93:80 Mar 09 14:52:43 volumio volumio[917]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 09 14:52:43 volumio volumio[917]: errno: -110, Mar 09 14:52:43 volumio volumio[917]: code: 'ETIMEDOUT', Mar 09 14:52:43 volumio volumio[917]: syscall: 'connect', Mar 09 14:52:43 volumio volumio[917]: address: '168.143.171.93', Mar 09 14:52:43 volumio volumio[917]: port: 80 Mar 09 14:52:43 volumio volumio[917]: } Mar 09 14:52:43 volumio volumio[917]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 14:52:43 volumio sudo[10072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 14:51 Mar 09 14:52:43 volumio sudo[10072]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"