-- Logs begin at Sat 2025-12-20 22:22:17 CST, end at Sat 2025-12-20 23:01:51 CST. --
Dec 20 23:01:03 volumio volumio[3296]: info: Clearing queue after UPNP request
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::ClearQueue
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::stop
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::stPlaybackTimer
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::updateTrackBlock
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrackBlock
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::pushState
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrack 0
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::volumioPushState
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::serviceStop
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrack 0
Dec 20 23:01:03 volumio volumio[3296]: info: ControllerMpd::stop
Dec 20 23:01:03 volumio volumio[3296]: verbose: ControllerMpd::sendMpdCommand stop
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::clearPlayQueue
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::saveQueue
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::volumioPushState
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::volumioPushQueue
Dec 20 23:01:03 volumio volumio[3296]: info: sendMpdCommand stop took 142 milliseconds
Dec 20 23:01:03 volumio volumio[3296]: info:
Dec 20 23:01:03 volumio volumio[3296]: ---------------------------- MPD announces state update: player
Dec 20 23:01:03 volumio volumio[3296]: info: ControllerMpd::getState
Dec 20 23:01:03 volumio volumio[3296]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 23:01:03 volumio volumio[3296]: info:
Dec 20 23:01:03 volumio volumio[3296]: ---------------------------- MPD announces system playlist update
Dec 20 23:01:03 volumio volumio[3296]: info: Ignoring MPD Status Update
Dec 20 23:01:03 volumio volumio[3296]: info: sendMpdCommand status took 3 milliseconds
Dec 20 23:01:03 volumio volumio[3296]: verbose: ControllerMpd::parseState
Dec 20 23:01:03 volumio volumio[3296]: info: ControllerMpd::pushState
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::servicePushState
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::pushState
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrack 0
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::volumioPushState
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrack 0
Dec 20 23:01:03 volumio volumio[3296]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 20 23:01:03 volumio volumio[3296]: verbose: CURRENT POSITION 0
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::syncState stateService stop
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::syncState currentStatus stop
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::pushState
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrack 0
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::volumioPushState
Dec 20 23:01:03 volumio volumio[3296]: info: No code
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::pushState
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrack 0
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::volumioPushState
Dec 20 23:01:03 volumio volumio[3296]: info: ------------------------------ 55ms
Dec 20 23:01:03 volumio volumio[3296]: error: updateQueue error: null
Dec 20 23:01:03 volumio volumio[3296]: info: ------------------------------ 55ms
Dec 20 23:01:03 volumio volumio[3296]: info: Starting UPNP Playback
Dec 20 23:01:03 volumio volumio[3296]: info: Preparing playback through UPNP
Dec 20 23:01:03 volumio volumio[3296]: info: CoreCommandRouter::volumioGetState
Dec 20 23:01:03 volumio volumio[3296]: info: CorePlayQueue::getTrack 0
Dec 20 23:01:03 volumio volumio[3296]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces system playlist update
Dec 20 23:01:04 volumio volumio[3296]: info: Ignoring MPD Status Update
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces system playlist update
Dec 20 23:01:04 volumio volumio[3296]: info: Ignoring MPD Status Update
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 3ms
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 2ms
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces system playlist update
Dec 20 23:01:04 volumio volumio[3296]: info: Ignoring MPD Status Update
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces system playlist update
Dec 20 23:01:04 volumio volumio[3296]: info: Ignoring MPD Status Update
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 2ms
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 2ms
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces system playlist update
Dec 20 23:01:04 volumio volumio[3296]: info: Ignoring MPD Status Update
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 2ms
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces system playlist update
Dec 20 23:01:04 volumio volumio[3296]: info: Ignoring MPD Status Update
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 1ms
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces state update: player
Dec 20 23:01:04 volumio volumio[3296]: info: ControllerMpd::getState
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 23:01:04 volumio volumio[3296]: info: sendMpdCommand status took 5 milliseconds
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::parseState
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 23:01:04 volumio volumio[3296]: info:
Dec 20 23:01:04 volumio volumio[3296]: ---------------------------- MPD announces state update: player
Dec 20 23:01:04 volumio volumio[3296]: info: ControllerMpd::getState
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 23:01:04 volumio volumio[3296]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::parseTrackInfo
Dec 20 23:01:04 volumio volumio[3296]: info: ControllerMpd::pushState
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::servicePushState
Dec 20 23:01:04 volumio volumio[3296]: verbose: In UPNP mode
Dec 20 23:01:04 volumio volumio[3296]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Losin' The Love","artist":"Joy Enriquez","album":"Joy Enriquez","uri":"http://m701.music.126.net/20251220232603/945c5c64bccb9638bd96f3853838274e/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1528759322/594e/534f/2cb0/3600d247f051340e0b652bc1b8d49824.mp3?vuutv=Bo3bcdFL8Tecl3UFBphbyHGh04UVjfCb3di2GS/9fLFGMTOprERq0sIYntG2OEJYl1VRydpza7xs4sMZt1HwUrqxmQfjE3A4YCEkXum17zg=","trackType":""}
Dec 20 23:01:04 volumio volumio[3296]: verbose: CURRENT POSITION 0
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::syncState stateService play
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::syncState currentStatus stop
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 11ms
Dec 20 23:01:04 volumio volumio[3296]: info: sendMpdCommand status took 4 milliseconds
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::parseState
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 23:01:04 volumio volumio[3296]: info: sendMpdCommand playlistinfo took 1 milliseconds
Dec 20 23:01:04 volumio volumio[3296]: verbose: ControllerMpd::parseTrackInfo
Dec 20 23:01:04 volumio volumio[3296]: info: ControllerMpd::pushState
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::servicePushState
Dec 20 23:01:04 volumio volumio[3296]: verbose: In UPNP mode
Dec 20 23:01:04 volumio volumio[3296]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Losin' The Love","artist":"Joy Enriquez","album":"Joy Enriquez","uri":"http://m701.music.126.net/20251220232603/945c5c64bccb9638bd96f3853838274e/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1528759322/594e/534f/2cb0/3600d247f051340e0b652bc1b8d49824.mp3?vuutv=Bo3bcdFL8Tecl3UFBphbyHGh04UVjfCb3di2GS/9fLFGMTOprERq0sIYntG2OEJYl1VRydpza7xs4sMZt1HwUrqxmQfjE3A4YCEkXum17zg=","trackType":""}
Dec 20 23:01:04 volumio volumio[3296]: verbose: CURRENT POSITION 0
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::syncState stateService play
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::syncState currentStatus play
Dec 20 23:01:04 volumio volumio[3296]: info: Received an update from plugin. extracting info from payload
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::pushState
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::volumioPushState
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::pushState
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::volumioPushState
Dec 20 23:01:04 volumio volumio[3296]: info: ------------------------------ 50ms
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::volumioGetQueue
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::getQueue
Dec 20 23:01:04 volumio volumio[3296]: info: CorePlayQueue::getQueue
Dec 20 23:01:04 volumio volumio[3296]: info: CoreCommandRouter::volumioGetQueue
Dec 20 23:01:04 volumio volumio[3296]: info: CoreStateMachine::getQueue
Dec 20 23:01:04 volumio volumio[3296]: info: CorePlayQueue::getQueue
Dec 20 23:01:50 volumio volumio[3296]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 20 23:01:50 volumio volumio[3296]: Error: connect ETIMEDOUT 74.86.118.24:443
Dec 20 23:01:50 volumio volumio[3296]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Dec 20 23:01:50 volumio volumio[3296]: errno: -110,
Dec 20 23:01:50 volumio volumio[3296]: code: 'ETIMEDOUT',
Dec 20 23:01:50 volumio volumio[3296]: syscall: 'connect',
Dec 20 23:01:50 volumio volumio[3296]: address: '74.86.118.24',
Dec 20 23:01:50 volumio volumio[3296]: port: 443
Dec 20 23:01:50 volumio volumio[3296]: }
Dec 20 23:01:50 volumio volumio[3296]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 20 23:01:51 volumio sudo[4466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 23:00
Dec 20 23:01:51 volumio sudo[4466]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST"
VOLUMIO_VERSION="3.569"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"