Jan 12 16:54:16 volumio volumio[157850]: info: Clearing queue after UPNP request Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::ClearQueue Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::stop Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::stPlaybackTimer Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::updateTrackBlock Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::getTrackBlock Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::pushState Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::getTrack 8 Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::volumioPushState Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::serviceStop Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::getTrack 8 Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::serviceStop Jan 12 16:54:16 volumio volumio[157850]: info: [1768208056527] ControllerUPNPBrowser::stop Jan 12 16:54:16 volumio volumio[157850]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::clearPlayQueue Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::saveQueue Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::volumioPushState Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::volumioPushQueue Jan 12 16:54:16 volumio volumio[157850]: info: Jan 12 16:54:16 volumio volumio[157850]: ---------------------------- MPD announces state update: player Jan 12 16:54:16 volumio volumio[157850]: info: sendMpdCommand stop took 28 milliseconds Jan 12 16:54:16 volumio volumio[157850]: info: ControllerMpd::getState Jan 12 16:54:16 volumio volumio[157850]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:54:16 volumio volumio[157850]: info: sendMpdCommand status took 0 milliseconds Jan 12 16:54:16 volumio volumio[157850]: verbose: ControllerMpd::parseState Jan 12 16:54:16 volumio volumio[157850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 16:54:16 volumio volumio[157850]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 12 16:54:16 volumio volumio[157850]: verbose: ControllerMpd::parseTrackInfo Jan 12 16:54:16 volumio volumio[157850]: info: ControllerMpd::pushState Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::servicePushState Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::pushState Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::getTrack 8 Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::volumioPushState Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::getTrack 8 Jan 12 16:54:16 volumio volumio[157850]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4e00ea2a331785cdc4cbc3b66bf213ad.wav?profile_id=0&convert=wav&subsong=9","artist":null,"album":null,"uri":"http://192.168.1.4:56923/content/4e00ea2a331785cdc4cbc3b66bf213ad.wav?profile_id=0&convert=wav&subsong=9","trackType":""} Jan 12 16:54:16 volumio volumio[157850]: verbose: CURRENT POSITION 8 Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::syncState stateService stop Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::syncState currentStatus stop Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::pushState Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::getTrack 8 Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::volumioPushState Jan 12 16:54:16 volumio volumio[157850]: info: No code Jan 12 16:54:16 volumio volumio[157850]: info: CoreStateMachine::pushState Jan 12 16:54:16 volumio volumio[157850]: info: CorePlayQueue::getTrack 8 Jan 12 16:54:16 volumio volumio[157850]: info: CoreCommandRouter::volumioPushState Jan 12 16:54:16 volumio volumio[157850]: info: ------------------------------ 7ms Jan 12 16:54:27 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:54:27 volumio volumio[157850]: info: Preload queue cleared Jan 12 16:54:28 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:54:29 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:54:39 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:54:39 volumio volumio[157850]: info: Preload queue cleared Jan 12 16:54:42 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:54:45 volumio volumio[157850]: info: Preload queue cleared Jan 12 16:54:47 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:54:49 volumio volumio[157850]: info: Preload queue cleared Jan 12 16:54:56 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:54:57 volumio volumio[157850]: info: Preload queue cleared Jan 12 16:55:01 volumio volumio[157850]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 16:55:01 volumio volumio[157850]: info: Preload queue cleared Jan 12 16:55:01 volumio volumio[157850]: info: Preloading song: upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35079 Jan 12 16:55:01 volumio volumio[157850]: info: Preloading song: upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35080 Jan 12 16:55:01 volumio volumio[157850]: info: Preloading song: upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35081 Jan 12 16:55:01 volumio volumio[157850]: info: Preloading song: upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35082 Jan 12 16:55:01 volumio volumio[157850]: info: Preloading song: upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35083 Jan 12 16:55:01 volumio volumio[157850]: info: Preloading song: upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35084 Jan 12 16:55:02 volumio volumio[157850]: info: Exploding uri upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35079 in service upnp_browser Jan 12 16:55:02 volumio volumio[157850]: info: Exploding uri upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35080 in service upnp_browser Jan 12 16:55:02 volumio volumio[157850]: info: Exploding uri upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35081 in service upnp_browser Jan 12 16:55:02 volumio volumio[157850]: info: Exploding uri upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35082 in service upnp_browser Jan 12 16:55:02 volumio volumio[157850]: info: Exploding uri upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35083 in service upnp_browser Jan 12 16:55:02 volumio volumio[157850]: info: Exploding uri upnp/http://192.168.1.4:2869/upnphost/udhisapi.dll?control=uuid:bf219b5a-de9e-487a-ad96-59bf70376371+urn:upnp-org:serviceId:ContentDirectory@14_63d9dd82_3dcc79d1-35084 in service upnp_browser Jan 12 16:55:03 volumio volumio[157850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:55:03 volumio volumio[157850]: Error: connect ETIMEDOUT 69.63.184.14:80 Jan 12 16:55:03 volumio volumio[157850]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 12 16:55:03 volumio volumio[157850]: errno: -110, Jan 12 16:55:03 volumio volumio[157850]: code: 'ETIMEDOUT', Jan 12 16:55:03 volumio volumio[157850]: syscall: 'connect', Jan 12 16:55:03 volumio volumio[157850]: address: '69.63.184.14', Jan 12 16:55:03 volumio volumio[157850]: port: 80 Jan 12 16:55:03 volumio volumio[157850]: } Jan 12 16:55:03 volumio volumio[157850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:55:03 volumio sudo[339988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 16:54' Jan 12 16:55:03 volumio sudo[339988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"