-- Logs begin at Sat 2025-12-27 04:58:46 UTC, end at Sat 2025-12-27 09:03:48 UTC. -- Dec 27 09:02:25 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 27 09:02:26 volumio dhcpd[11616]: DHCPDISCOVER from 00:90:4c:c5:12:38 (volumio) via wlan0 Dec 27 09:02:27 volumio dhcpd[11616]: DHCPOFFER on 192.168.211.2 to 00:90:4c:c5:12:38 (volumio) via wlan0 Dec 27 09:02:32 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 27 09:02:49 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::ClearQueue Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::stop Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:03:02 volumio volumio[9768]: UNSET VOLATILE Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::stPlaybackTimer Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::updateTrackBlock Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrackBlock Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 11 Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::serviceStop Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 11 Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::serviceStop Dec 27 09:03:02 volumio volumio[9768]: info: [1766826182524] ControllerUPNPBrowser::stop Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::clearPlayQueue Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::saveQueue Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushQueue Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::addQueueItems Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::addQueueItems Dec 27 09:03:02 volumio volumio[9768]: info: Adding Item to queue: upnp/folder/http://192.168.2.27:8200/ctl/ContentDir@1$14$6A Dec 27 09:03:02 volumio volumio[9768]: info: Exploding uri upnp/folder/http://192.168.2.27:8200/ctl/ContentDir@1$14$6A in service upnp_browser Dec 27 09:03:02 volumio volumio[9768]: info: Dec 27 09:03:02 volumio volumio[9768]: ---------------------------- MPD announces state update: player Dec 27 09:03:02 volumio volumio[9768]: info: ControllerMpd::getState Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::parseState Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:03:02 volumio volumio[9768]: info: ControllerMpd::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::servicePushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 11 Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 11 Dec 27 09:03:02 volumio volumio[9768]: 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":"5675.wav","artist":null,"album":null,"uri":"http://192.168.2.27:8200/MediaItems/5675.wav","trackType":"wav"} Dec 27 09:03:02 volumio volumio[9768]: verbose: CURRENT POSITION 11 Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::syncState stateService stop Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::syncState currentStatus stop Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 11 Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: No code Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 11 Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: ------------------------------ 32ms Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::saveQueue Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushQueue Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::updateTrackBlock Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrackBlock Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPlay Dec 27 09:03:02 volumio volumio[9768]: UNSET VOLATILE Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::play index 0 Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::stop Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:03:02 volumio volumio[9768]: UNSET VOLATILE Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::play index undefined Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::startPlaybackTimer Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:02 volumio volumio[9768]: info: [1766826182599] ControllerUPNPBrowser::clearAddPlayTrack Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 09:03:02 volumio volumio[9768]: info: Dec 27 09:03:02 volumio volumio[9768]: ---------------------------- MPD announces system playlist update Dec 27 09:03:02 volumio volumio[9768]: info: Ignoring MPD Status Update Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.27:8200/MediaItems/6118.wav" Dec 27 09:03:02 volumio volumio[9768]: error: updateQueue error: null Dec 27 09:03:02 volumio volumio[9768]: info: ------------------------------ 4ms Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.27:8200/MediaItems/6118.wav" Dec 27 09:03:02 volumio volumio[9768]: info: Dec 27 09:03:02 volumio volumio[9768]: ---------------------------- MPD announces system playlist update Dec 27 09:03:02 volumio volumio[9768]: info: Ignoring MPD Status Update Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand play Dec 27 09:03:02 volumio volumio[9768]: info: ------------------------------ 4ms Dec 27 09:03:02 volumio volumio[9768]: info: Dec 27 09:03:02 volumio volumio[9768]: ---------------------------- MPD announces state update: player Dec 27 09:03:02 volumio volumio[9768]: info: ControllerMpd::getState Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:03:02 volumio volumio[9768]: info: Dec 27 09:03:02 volumio volumio[9768]: ---------------------------- MPD announces state update: player Dec 27 09:03:02 volumio volumio[9768]: info: ControllerMpd::getState Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand status Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::parseState Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::parseState Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:03:02 volumio volumio[9768]: info: ControllerMpd::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::servicePushState Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:02 volumio volumio[9768]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2918,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6118.wav","artist":null,"album":null,"uri":"http://192.168.2.27:8200/MediaItems/6118.wav","trackType":"wav"} Dec 27 09:03:02 volumio volumio[9768]: verbose: CURRENT POSITION 0 Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::syncState stateService play Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::syncState currentStatus stop Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: ------------------------------ 37ms Dec 27 09:03:02 volumio volumio[9768]: verbose: ControllerMpd::parseTrackInfo Dec 27 09:03:02 volumio volumio[9768]: info: ControllerMpd::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::servicePushState Dec 27 09:03:02 volumio volumio[9768]: info: CorePlayQueue::getTrack 0 Dec 27 09:03:02 volumio volumio[9768]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":2918,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6118.wav","artist":null,"album":null,"uri":"http://192.168.2.27:8200/MediaItems/6118.wav","trackType":"wav"} Dec 27 09:03:02 volumio volumio[9768]: verbose: CURRENT POSITION 0 Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::syncState stateService play Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::syncState currentStatus play Dec 27 09:03:02 volumio volumio[9768]: info: Received an update from plugin. extracting info from payload Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: CoreStateMachine::pushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::volumioPushState Dec 27 09:03:02 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 09:03:02 volumio volumio[9768]: info: ------------------------------ 59ms Dec 27 09:03:19 volumio volumio[9768]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 27 09:03:26 volumio volumio[9768]: error: Cannot download Available plugins list: Error: ETIMEDOUT Dec 27 09:03:31 volumio dhcpd[11616]: DHCPDISCOVER from 00:90:4c:c5:12:38 (volumio) via wlan0 Dec 27 09:03:32 volumio dhcpd[11616]: DHCPOFFER on 192.168.211.2 to 00:90:4c:c5:12:38 (volumio) via wlan0 Dec 27 09:03:48 volumio volumio[9768]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 09:03:48 volumio volumio[9768]: { Error: connect ETIMEDOUT 31.13.85.34:443 Dec 27 09:03:48 volumio volumio[9768]: at Object._errnoException (util.js:1022:11) Dec 27 09:03:48 volumio volumio[9768]: at _exceptionWithHostPort (util.js:1044:20) Dec 27 09:03:48 volumio volumio[9768]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Dec 27 09:03:48 volumio volumio[9768]: code: 'ETIMEDOUT', Dec 27 09:03:48 volumio volumio[9768]: errno: 'ETIMEDOUT', Dec 27 09:03:48 volumio volumio[9768]: syscall: 'connect', Dec 27 09:03:48 volumio volumio[9768]: address: '31.13.85.34', Dec 27 09:03:48 volumio volumio[9768]: port: 443 } Dec 27 09:03:48 volumio volumio[9768]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 09:03:48 volumio sudo[12413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 09:02 Dec 27 09:03:48 volumio sudo[12413]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"