-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-09-18 13:22:20 CST. -- Sep 18 13:21:02 volumio volumio[9948]: info: VolumeController::SetAlsaVolume100 Sep 18 13:21:02 volumio volumio[9948]: info: CoreStateMachine::pushState Sep 18 13:21:02 volumio volumio[9948]: info: CorePlayQueue::getTrack 1 Sep 18 13:21:02 volumio volumio[9948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 13:21:02 volumio volumio[9948]: info: CoreCommandRouter::volumioPushState Sep 18 13:21:07 volumio wpa_supplicant[2248]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 18 13:21:09 volumio wpa_supplicant[2248]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 18 13:21:09 volumio volumio[9948]: info: CoreCommandRouter::volumioGetState Sep 18 13:21:09 volumio volumio[9948]: info: CorePlayQueue::getTrack 1 Sep 18 13:21:09 volumio wpa_supplicant[2248]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 18 13:21:12 volumio volumio[9948]: info: CoreCommandRouter::volumioPause Sep 18 13:21:12 volumio volumio[9948]: info: CoreStateMachine::pause Sep 18 13:21:12 volumio volumio[9948]: info: CoreStateMachine::stPlaybackTimer Sep 18 13:21:12 volumio volumio[9948]: info: CoreStateMachine::servicePause Sep 18 13:21:12 volumio volumio[9948]: info: CorePlayQueue::getTrack 1 Sep 18 13:21:12 volumio volumio[9948]: info: CoreCommandRouter::servicePause Sep 18 13:21:12 volumio volumio[9948]: info: ControllerMpd::pause Sep 18 13:21:12 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand pause Sep 18 13:21:12 volumio volumio[9948]: info: sendMpdCommand pause took 2 milliseconds Sep 18 13:21:23 volumio wpa_supplicant[2248]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 18 13:21:26 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:26 volumio volumio[9948]: info: Preload queue cleared Sep 18 13:21:28 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:31 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:33 volumio volumio[9948]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 18 13:21:33 volumio volumio[9948]: info: Preload queue cleared Sep 18 13:21:34 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:35 volumio volumio[9948]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 18 13:21:35 volumio volumio[9948]: info: Preload queue cleared Sep 18 13:21:37 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:37 volumio volumio[9948]: info: Preload queue cleared Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::ClearQueue Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::stop Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::updateTrackBlock Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::getTrackBlock Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::stPlaybackTimer Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::pushState Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::getTrack 1 Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::volumioPushState Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::serviceStop Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::getTrack 1 Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::serviceStop Sep 18 13:21:37 volumio volumio[9948]: info: ControllerMpd::stop Sep 18 13:21:37 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::clearPlayQueue Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::saveQueue Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::volumioPushQueue Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::addQueueItems Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::addQueueItems Sep 18 13:21:37 volumio volumio[9948]: info: Preload queue cleared Sep 18 13:21:37 volumio volumio[9948]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8 Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::volumioPushQueue Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::saveQueue Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::updateTrackBlock Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::getTrackBlock Sep 18 13:21:37 volumio volumio[9948]: info: CoreCommandRouter::volumioPlay Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::play index 0 Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::stop Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::play index undefined Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::getTrack 0 Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::startPlaybackTimer Sep 18 13:21:37 volumio volumio[9948]: info: CorePlayQueue::getTrack 0 Sep 18 13:21:37 volumio volumio[9948]: info: [1758172897636] ControllerWebradio::clearAddPlayTrack Sep 18 13:21:37 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand stop Sep 18 13:21:37 volumio volumio[9948]: info: sendMpdCommand stop took 42 milliseconds Sep 18 13:21:37 volumio volumio[9948]: info: sendMpdCommand stop took 5 milliseconds Sep 18 13:21:37 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand clear Sep 18 13:21:37 volumio volumio[9948]: info: Sep 18 13:21:37 volumio volumio[9948]: ---------------------------- MPD announces system playlist update Sep 18 13:21:37 volumio volumio[9948]: info: Ignoring MPD Status Update Sep 18 13:21:37 volumio volumio[9948]: info: sendMpdCommand clear took 1 milliseconds Sep 18 13:21:37 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8" Sep 18 13:21:37 volumio volumio[9948]: error: updateQueue error: null Sep 18 13:21:37 volumio volumio[9948]: info: Sep 18 13:21:37 volumio volumio[9948]: ---------------------------- MPD announces system playlist update Sep 18 13:21:37 volumio volumio[9948]: info: Ignoring MPD Status Update Sep 18 13:21:37 volumio volumio[9948]: info: ------------------------------ 4ms Sep 18 13:21:37 volumio volumio[9948]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8" took 2 milliseconds Sep 18 13:21:37 volumio volumio[9948]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 18 13:21:37 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand play Sep 18 13:21:37 volumio volumio[9948]: info: ------------------------------ 4ms Sep 18 13:21:37 volumio volumio[9948]: info: sendMpdCommand play took 2 milliseconds Sep 18 13:21:40 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:40 volumio volumio[9948]: info: Sep 18 13:21:40 volumio volumio[9948]: ---------------------------- MPD announces state update: player Sep 18 13:21:40 volumio volumio[9948]: info: ControllerMpd::getState Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand status Sep 18 13:21:40 volumio volumio[9948]: info: Sep 18 13:21:40 volumio volumio[9948]: ---------------------------- MPD announces state update: player Sep 18 13:21:40 volumio volumio[9948]: info: sendMpdCommand status took 22 milliseconds Sep 18 13:21:40 volumio volumio[9948]: info: ControllerMpd::getState Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand status Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::parseState Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 13:21:40 volumio volumio[9948]: info: sendMpdCommand status took 3 milliseconds Sep 18 13:21:40 volumio volumio[9948]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::parseState Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::parseTrackInfo Sep 18 13:21:40 volumio volumio[9948]: info: ControllerMpd::pushState Sep 18 13:21:40 volumio volumio[9948]: info: CoreCommandRouter::servicePushState Sep 18 13:21:40 volumio volumio[9948]: info: CorePlayQueue::getTrack 0 Sep 18 13:21:40 volumio volumio[9948]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_fourfm-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Sep 18 13:21:40 volumio volumio[9948]: verbose: CURRENT POSITION 0 Sep 18 13:21:40 volumio volumio[9948]: info: CoreStateMachine::syncState stateService play Sep 18 13:21:40 volumio volumio[9948]: info: CoreStateMachine::syncState currentStatus stop Sep 18 13:21:40 volumio volumio[9948]: info: ------------------------------ 39ms Sep 18 13:21:40 volumio volumio[9948]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 18 13:21:40 volumio volumio[9948]: verbose: ControllerMpd::parseTrackInfo Sep 18 13:21:40 volumio volumio[9948]: info: ControllerMpd::pushState Sep 18 13:21:40 volumio volumio[9948]: info: CoreCommandRouter::servicePushState Sep 18 13:21:40 volumio volumio[9948]: info: CorePlayQueue::getTrack 0 Sep 18 13:21:40 volumio volumio[9948]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_fourfm-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_55057080/live/ww/bbc_radio_fourfm/bbc_radio_fourfm.isml/bbc_radio_fourfm-audio%3d128000.norewind.m3u8","trackType":"m3u8"} Sep 18 13:21:40 volumio volumio[9948]: verbose: CURRENT POSITION 0 Sep 18 13:21:40 volumio volumio[9948]: info: CoreStateMachine::syncState stateService play Sep 18 13:21:40 volumio volumio[9948]: info: CoreStateMachine::syncState currentStatus play Sep 18 13:21:40 volumio volumio[9948]: info: Received an update from plugin. extracting info from payload Sep 18 13:21:40 volumio volumio[9948]: info: CoreStateMachine::pushState Sep 18 13:21:40 volumio volumio[9948]: info: CorePlayQueue::getTrack 0 Sep 18 13:21:40 volumio volumio[9948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 13:21:40 volumio volumio[9948]: info: CoreCommandRouter::volumioPushState Sep 18 13:21:40 volumio volumio[9948]: info: CoreStateMachine::pushState Sep 18 13:21:40 volumio volumio[9948]: info: CoreCommandRouter::volumioPushState Sep 18 13:21:40 volumio volumio[9948]: info: ------------------------------ 47ms Sep 18 13:21:43 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:46 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:47 volumio wpa_supplicant[2248]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 18 13:21:49 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:53 volumio volumio[9948]: info: VolumeController::SetAlsaVolume86 Sep 18 13:21:53 volumio volumio[9948]: info: CoreStateMachine::pushState Sep 18 13:21:53 volumio volumio[9948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 13:21:53 volumio volumio[9948]: info: CoreCommandRouter::volumioPushState Sep 18 13:21:55 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:21:57 volumio volumio[9948]: info: CoreCommandRouter::volumioGetState Sep 18 13:21:58 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:22:04 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:22:13 volumio dnsmasq-dhcp[983]: no address range available for DHCP request via wlan0 Sep 18 13:22:19 volumio volumio[9948]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 13:22:19 volumio volumio[9948]: Error: connect ETIMEDOUT 31.13.94.23:443 Sep 18 13:22:19 volumio volumio[9948]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 18 13:22:19 volumio volumio[9948]: errno: -110, Sep 18 13:22:19 volumio volumio[9948]: code: 'ETIMEDOUT', Sep 18 13:22:19 volumio volumio[9948]: syscall: 'connect', Sep 18 13:22:19 volumio volumio[9948]: address: '31.13.94.23', Sep 18 13:22:19 volumio volumio[9948]: port: 443 Sep 18 13:22:19 volumio volumio[9948]: } Sep 18 13:22:19 volumio volumio[9948]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 13:22:20 volumio sudo[11092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-18 13:21 Sep 18 13:22:20 volumio sudo[11092]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"