-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-11-01 15:15:06 CET. -- Nov 01 15:14:01 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 01 15:14:01 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 15:14:03 volumio volumio[1179]: info: Preload queue cleared Nov 01 15:14:05 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 01 15:14:05 volumio volumio[1179]: info: Preload queue cleared Nov 01 15:14:14 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 15:14:14 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 15:14:14 volumio volumio[1179]: info: Discovery: Getting this device information Nov 01 15:14:14 volumio volumio[1179]: info: CoreCommandRouter::volumioGetState Nov 01 15:14:14 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 15:14:23 volumio volumio[1179]: info: Preload queue cleared Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::ClearQueue Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::stop Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::stPlaybackTimer Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::updateTrackBlock Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::getTrackBlock Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::pushState Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::volumioPushState Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::serviceStop Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::serviceStop Nov 01 15:14:23 volumio volumio[1179]: info: [1762006463409] ControllerWebradio::stop Nov 01 15:14:23 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::clearPlayQueue Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::saveQueue Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::volumioPushQueue Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::addQueueItems Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::addQueueItems Nov 01 15:14:23 volumio volumio[1179]: info: Preload queue cleared Nov 01 15:14:23 volumio volumio[1179]: info: Adding Item to queue: https://jazz.stream.laut.fm/jazz?ref=radiodns Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::volumioPushQueue Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::saveQueue Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::updateTrackBlock Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::getTrackBlock Nov 01 15:14:23 volumio volumio[1179]: info: CoreCommandRouter::volumioPlay Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::play index 0 Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::stop Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::play index undefined Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:23 volumio volumio[1179]: info: CoreStateMachine::startPlaybackTimer Nov 01 15:14:23 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:23 volumio volumio[1179]: info: [1762006463425] ControllerWebradio::clearAddPlayTrack Nov 01 15:14:23 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 15:14:23 volumio volumio[1179]: info: [ASDebug] CurState: stop PrevState: play Nov 01 15:14:23 volumio volumio[1179]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 01 15:14:23 volumio volumio[1179]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Nov 01 15:14:23 volumio volumio[1179]: info: Nov 01 15:14:23 volumio volumio[1179]: ---------------------------- MPD announces state update: player Nov 01 15:14:23 volumio volumio[1179]: info: sendMpdCommand stop took 31 milliseconds Nov 01 15:14:23 volumio volumio[1179]: info: ControllerMpd::getState Nov 01 15:14:23 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand status Nov 01 15:14:23 volumio volumio[1179]: info: sendMpdCommand stop took 16 milliseconds Nov 01 15:14:23 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 15:14:23 volumio volumio[1179]: info: Nov 01 15:14:23 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 01 15:14:23 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 01 15:14:23 volumio volumio[1179]: info: sendMpdCommand status took 4 milliseconds Nov 01 15:14:23 volumio volumio[1179]: info: sendMpdCommand clear took 4 milliseconds Nov 01 15:14:23 volumio volumio[1179]: verbose: ControllerMpd::parseState Nov 01 15:14:23 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 15:14:23 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand load "https://jazz.stream.laut.fm/jazz?ref=radiodns" Nov 01 15:14:23 volumio volumio[1179]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 01 15:14:23 volumio volumio[1179]: error: updateQueue error: null Nov 01 15:14:23 volumio volumio[1179]: info: ------------------------------ 3ms Nov 01 15:14:24 volumio volumio[1179]: info: [ASDebug] Togle GPIO: OFF Nov 01 15:14:36 volumio volumio[1179]: info: CoreCommandRouter::volumioPlay Nov 01 15:14:36 volumio volumio[1179]: info: CoreStateMachine::play index undefined Nov 01 15:14:36 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:36 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:36 volumio volumio[1179]: info: CoreStateMachine::startPlaybackTimer Nov 01 15:14:36 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:36 volumio volumio[1179]: info: [1762006476138] ControllerWebradio::clearAddPlayTrack Nov 01 15:14:36 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 15:14:36 volumio ntpd[997]: Soliciting pool server 2606:4700:f1::123 Nov 01 15:14:37 volumio volumio[1179]: info: sendMpdCommand playlistinfo took 14016 milliseconds Nov 01 15:14:37 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand add "https://jazz.stream.laut.fm/jazz?ref=radiodns" Nov 01 15:14:37 volumio volumio[1179]: verbose: ControllerMpd::parseTrackInfo Nov 01 15:14:37 volumio volumio[1179]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 01 15:14:37 volumio volumio[1179]: info: ------------------------------ 14022ms Nov 01 15:14:37 volumio volumio[1179]: info: Nov 01 15:14:37 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 01 15:14:37 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 01 15:14:37 volumio volumio[1179]: info: sendMpdCommand stop took 1325 milliseconds Nov 01 15:14:37 volumio volumio[1179]: info: sendMpdCommand add "https://jazz.stream.laut.fm/jazz?ref=radiodns" took 2 milliseconds Nov 01 15:14:37 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 15:14:37 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 15:14:37 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand play Nov 01 15:14:37 volumio volumio[1179]: Upnp client error: Error: This socket has been ended by the other party Nov 01 15:14:37 volumio volumio[1179]: info: Nov 01 15:14:37 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 01 15:14:37 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 01 15:14:37 volumio volumio[1179]: info: ------------------------------ 2ms Nov 01 15:14:37 volumio volumio[1179]: info: sendMpdCommand clear took 2 milliseconds Nov 01 15:14:37 volumio volumio[1179]: info: sendMpdCommand play took 2 milliseconds Nov 01 15:14:37 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand load "https://jazz.stream.laut.fm/jazz?ref=radiodns" Nov 01 15:14:44 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 15:14:44 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 15:14:44 volumio volumio[1179]: info: Discovery: Getting this device information Nov 01 15:14:44 volumio volumio[1179]: info: CoreCommandRouter::volumioGetState Nov 01 15:14:44 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 15:14:47 volumio volumio[1179]: info: Preload queue cleared Nov 01 15:14:47 volumio volumio[1179]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::ClearQueue Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::stop Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:47 volumio volumio[1179]: info: CorePlayQueue::clearPlayQueue Nov 01 15:14:47 volumio volumio[1179]: info: CorePlayQueue::saveQueue Nov 01 15:14:47 volumio volumio[1179]: info: CoreCommandRouter::volumioPushQueue Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::addQueueItems Nov 01 15:14:47 volumio volumio[1179]: info: CorePlayQueue::addQueueItems Nov 01 15:14:47 volumio volumio[1179]: info: Preload queue cleared Nov 01 15:14:47 volumio volumio[1179]: info: Adding Item to queue: https://jazz.stream.laut.fm/jazz?ref=radiodns Nov 01 15:14:47 volumio volumio[1179]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 01 15:14:47 volumio volumio[1179]: info: CoreCommandRouter::volumioPushQueue Nov 01 15:14:47 volumio volumio[1179]: info: CorePlayQueue::saveQueue Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::updateTrackBlock Nov 01 15:14:47 volumio volumio[1179]: info: CorePlayQueue::getTrackBlock Nov 01 15:14:47 volumio volumio[1179]: info: CoreCommandRouter::volumioPlay Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::play index 0 Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::stop Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::play index undefined Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 15:14:47 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:47 volumio volumio[1179]: info: CoreStateMachine::startPlaybackTimer Nov 01 15:14:47 volumio volumio[1179]: info: CorePlayQueue::getTrack 0 Nov 01 15:14:47 volumio volumio[1179]: info: [1762006487954] ControllerWebradio::clearAddPlayTrack Nov 01 15:14:47 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 15:14:48 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb0944f.8f6a1eb0 does not match aorg 0xecb09457.8f6a2254 from server@213.222.217.10 xmt 0xecb09450.32705c57 Nov 01 15:14:50 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09450.8f6a1a81 does not match aorg 0xecb09456.8f70e989 from server@178.215.228.24 xmt 0xecb09451.345dd6e0 Nov 01 15:14:51 volumio volumio[1179]: Upnp client error: Error: This socket has been ended by the other party Nov 01 15:14:51 volumio volumio[1179]: error: updateQueue error: null Nov 01 15:14:51 volumio volumio[1179]: info: ------------------------------ 14018ms Nov 01 15:14:51 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand add "https://jazz.stream.laut.fm/jazz?ref=radiodns" Nov 01 15:14:51 volumio volumio[1179]: info: sendMpdCommand stop took 3528 milliseconds Nov 01 15:14:51 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 15:14:51 volumio volumio[1179]: info: Nov 01 15:14:51 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 01 15:14:51 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 01 15:14:51 volumio volumio[1179]: info: Nov 01 15:14:51 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 01 15:14:51 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 01 15:14:51 volumio volumio[1179]: info: sendMpdCommand add "https://jazz.stream.laut.fm/jazz?ref=radiodns" took 1 milliseconds Nov 01 15:14:51 volumio volumio[1179]: info: sendMpdCommand clear took 1 milliseconds Nov 01 15:14:51 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 15:14:51 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand play Nov 01 15:14:51 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand load "https://jazz.stream.laut.fm/jazz?ref=radiodns" Nov 01 15:14:51 volumio volumio[1179]: error: updateQueue error: null Nov 01 15:14:51 volumio volumio[1179]: error: updateQueue error: null Nov 01 15:14:51 volumio volumio[1179]: info: ------------------------------ 1ms Nov 01 15:14:51 volumio volumio[1179]: info: ------------------------------ 1ms Nov 01 15:14:51 volumio volumio[1179]: info: sendMpdCommand play took 1 milliseconds Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09454.8f6c3fbe does not match aorg 0xecb0945a.8f70f666 from server@51.68.141.5 xmt 0xecb09455.3cf0025c Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09454.8f6d3302 does not match aorg 0xecb09456.8f71e1ee from server@162.159.200.1 xmt 0xecb09455.3dfe4f1e Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09454.8f6a5823 does not match aorg 0xecb0945a.8f6a3846 from server@156.17.20.254 xmt 0xecb09455.3cc8cf8f Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09454.8f6cbf38 does not match aorg 0xecb09456.8f70e989 from server@178.215.228.24 xmt 0xecb09455.4022d9b2 Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09455.8f6a0e39 does not match aorg 0xecb09457.8f6a2254 from server@213.222.217.10 xmt 0xecb09456.326ab583 Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09456.8f6f0b48 does not match aorg 0xecb0945a.8f70f666 from server@51.68.141.5 xmt 0xecb09457.8c3ec77a Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09456.8f6c848d does not match aorg 0xecb0945e.8f69981d from server@193.59.134.156 xmt 0xecb09457.8c4cbfb6 Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09456.8f69aa9b does not match aorg 0xecb0945a.8f6a3846 from server@156.17.20.254 xmt 0xecb09457.8b98e87e Nov 01 15:14:55 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09456.8f6ddfdc does not match aorg 0xecb0945c.8f6c32c4 from server@46.171.0.154 xmt 0xecb09457.8bf93e91 Nov 01 15:14:58 volumio ntpd[997]: Soliciting pool server 193.70.94.182 Nov 01 15:14:58 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb09458.8f6c1bb6 does not match aorg 0xecb0945c.8f6c32c4 from server@46.171.0.154 xmt 0xecb09459.31c82c6f Nov 01 15:14:59 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb0945a.8f6db2fa does not match aorg 0xecb0945e.8f69981d from server@193.59.134.156 xmt 0xecb0945b.3238b5e0 Nov 01 15:14:59 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb0945a.8f6f0c85 does not match aorg 0xecb0945c.8f6c32c4 from server@46.171.0.154 xmt 0xecb0945b.319a9ea4 Nov 01 15:15:02 volumio ntpd[997]: receive: Unexpected origin timestamp 0xecb0945c.8f6a2327 does not match aorg 0xecb0945e.8f69981d from server@193.59.134.156 xmt 0xecb0945d.3228dcbe Nov 01 15:15:05 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand add "https://jazz.stream.laut.fm/jazz?ref=radiodns" Nov 01 15:15:05 volumio volumio[1179]: info: Nov 01 15:15:05 volumio volumio[1179]: ---------------------------- MPD announces system playlist update Nov 01 15:15:05 volumio volumio[1179]: info: Ignoring MPD Status Update Nov 01 15:15:05 volumio volumio[1179]: info: sendMpdCommand add "https://jazz.stream.laut.fm/jazz?ref=radiodns" took 0 milliseconds Nov 01 15:15:05 volumio volumio[1179]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 15:15:05 volumio volumio[1179]: verbose: ControllerMpd::sendMpdCommand play Nov 01 15:15:05 volumio volumio[1179]: Upnp client error: Error: This socket has been ended by the other party Nov 01 15:15:05 volumio volumio[1179]: info: ------------------------------ 1ms Nov 01 15:15:05 volumio volumio[1179]: info: sendMpdCommand play took 1 milliseconds Nov 01 15:15:06 volumio ntpd[997]: Soliciting pool server 162.159.200.123 Nov 01 15:15:06 volumio volumio[1179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 15:15:06 volumio volumio[1179]: Error: read ECONNRESET Nov 01 15:15:06 volumio volumio[1179]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Nov 01 15:15:06 volumio volumio[1179]: errno: -104, Nov 01 15:15:06 volumio volumio[1179]: code: 'ECONNRESET', Nov 01 15:15:06 volumio volumio[1179]: syscall: 'read' Nov 01 15:15:06 volumio volumio[1179]: } Nov 01 15:15:06 volumio volumio[1179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 15:15:06 volumio sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 15:14 Nov 01 15:15:06 volumio sudo[2676]: 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"