-- Logs begin at Sun 2024-11-24 22:48:43 UTC, end at Sun 2024-11-24 23:32:03 UTC. -- Nov 24 23:31:49 volumio volumio-remote-updater[363]: updates.volumio.org: Device or resource busy Nov 24 23:31:49 volumio volumio-remote-updater[363]: failed to retrieve from http://updates.volumio.org/x86_amd64/volumio/3.779/volumio_current.sqsh Nov 24 23:31:56 volumio volumio[2536]: info: Nov 24 23:31:56 volumio volumio[2536]: ---------------------------- MPD announces system playlist update Nov 24 23:31:56 volumio volumio[2536]: info: Ignoring MPD Status Update Nov 24 23:31:56 volumio volumio[2536]: info: Nov 24 23:31:56 volumio volumio[2536]: ---------------------------- MPD announces state update: player Nov 24 23:31:56 volumio volumio[2536]: info: ControllerMpd::getState Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::sendMpdCommand status Nov 24 23:31:56 volumio volumio[2536]: info: Nov 24 23:31:56 volumio volumio[2536]: ---------------------------- MPD announces system playlist update Nov 24 23:31:56 volumio volumio[2536]: info: Ignoring MPD Status Update Nov 24 23:31:56 volumio volumio[2536]: info: Nov 24 23:31:56 volumio volumio[2536]: ---------------------------- MPD announces state update: player Nov 24 23:31:56 volumio volumio[2536]: info: ControllerMpd::getState Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::sendMpdCommand status Nov 24 23:31:56 volumio volumio[2536]: info: ------------------------------ 7ms Nov 24 23:31:56 volumio volumio[2536]: info: sendMpdCommand status took 7 milliseconds Nov 24 23:31:56 volumio volumio[2536]: info: ------------------------------ 6ms Nov 24 23:31:56 volumio volumio[2536]: info: sendMpdCommand status took 9 milliseconds Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::parseState Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::parseState Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 23:31:56 volumio volumio[2536]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 24 23:31:56 volumio volumio[2536]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::parseTrackInfo Nov 24 23:31:56 volumio volumio[2536]: verbose: ControllerMpd::parseTrackInfo Nov 24 23:31:56 volumio volumio[2536]: info: ControllerMpd::pushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::servicePushState Nov 24 23:31:56 volumio volumio[2536]: info: CorePlayQueue::getTrack 0 Nov 24 23:31:56 volumio volumio[2536]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billy Vera And The Beaters - At This Moment","artist":"Hot Hitz 80's","album":null,"uri":"http://216.245.210.78:9900/stream","trackType":""} Nov 24 23:31:56 volumio volumio[2536]: verbose: CURRENT POSITION 0 Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::syncState stateService play Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::syncState currentStatus play Nov 24 23:31:56 volumio volumio[2536]: info: Received an update from plugin. extracting info from payload Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::pushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::volumioPushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::pushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::volumioPushState Nov 24 23:31:56 volumio volumio[2536]: info: ControllerMpd::pushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::servicePushState Nov 24 23:31:56 volumio volumio[2536]: info: CorePlayQueue::getTrack 0 Nov 24 23:31:56 volumio volumio[2536]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billy Vera And The Beaters - At This Moment","artist":"Hot Hitz 80's","album":null,"uri":"http://216.245.210.78:9900/stream","trackType":""} Nov 24 23:31:56 volumio volumio[2536]: verbose: CURRENT POSITION 0 Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::syncState stateService play Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::syncState currentStatus play Nov 24 23:31:56 volumio volumio[2536]: info: Received an update from plugin. extracting info from payload Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::pushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::volumioPushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreStateMachine::pushState Nov 24 23:31:56 volumio volumio[2536]: info: CoreCommandRouter::volumioPushState Nov 24 23:31:57 volumio volumio[2536]: info: ------------------------------ 47ms Nov 24 23:31:57 volumio volumio[2536]: info: ------------------------------ 51ms Nov 24 23:32:01 volumio volumio[2536]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 23:32:01 volumio volumio[2536]: Error: connect ETIMEDOUT 199.59.148.209:80 Nov 24 23:32:01 volumio volumio[2536]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 24 23:32:01 volumio volumio[2536]: errno: -110, Nov 24 23:32:01 volumio volumio[2536]: code: 'ETIMEDOUT', Nov 24 23:32:01 volumio volumio[2536]: syscall: 'connect', Nov 24 23:32:01 volumio volumio[2536]: address: '199.59.148.209', Nov 24 23:32:01 volumio volumio[2536]: port: 80 Nov 24 23:32:01 volumio volumio[2536]: } Nov 24 23:32:01 volumio volumio[2536]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 23:32:03 volumio sudo[3004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 23:31 Nov 24 23:32:03 volumio sudo[3004]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 03:20:44 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="e524350c1f6dfee48683e2ce9db25952"