-- Logs begin at Sat 2024-07-20 19:34:26 CST, end at Sat 2024-07-20 19:46:20 CST. -- Jul 20 19:45:17 volumio ntpd[1160]: 95.111.202.5 local addr 192.168.31.62 -> Jul 20 19:45:27 volumio volumio[2673]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 20 19:45:36 volumio volumio[2673]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 20 19:45:36 volumio volumio[2673]: info: Getting webradio selection Jul 20 19:45:50 volumio volumio[2673]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::ClearQueue Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::stop Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::stPlaybackTimer Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::updateTrackBlock Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrackBlock Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::pushState Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrack 0 Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::volumioPushState Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::serviceStop Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrack 0 Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::serviceStop Jul 20 19:46:18 volumio volumio[2673]: info: ControllerMpd::stop Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand stop Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::clearPlayQueue Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::saveQueue Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::volumioPushQueue Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::addQueueItems Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::addQueueItems Jul 20 19:46:18 volumio volumio[2673]: info: Preload queue cleared Jul 20 19:46:18 volumio volumio[2673]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960 Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::volumioPushQueue Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::saveQueue Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::updateTrackBlock Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrackBlock Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::volumioPlay Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::play index 0 Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::stop Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::play index undefined Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrack 0 Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::startPlaybackTimer Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrack 0 Jul 20 19:46:18 volumio volumio[2673]: info: [1721475978306] ControllerWebradio::clearAddPlayTrack Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand stop Jul 20 19:46:18 volumio volumio[2673]: info: Jul 20 19:46:18 volumio volumio[2673]: ---------------------------- MPD announces state update: player Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand stop took 67 milliseconds Jul 20 19:46:18 volumio volumio[2673]: info: ControllerMpd::getState Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand status Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand stop took 41 milliseconds Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand clear Jul 20 19:46:18 volumio volumio[2673]: info: Jul 20 19:46:18 volumio volumio[2673]: ---------------------------- MPD announces state update: player Jul 20 19:46:18 volumio volumio[2673]: info: ControllerMpd::getState Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand status Jul 20 19:46:18 volumio volumio[2673]: info: Jul 20 19:46:18 volumio volumio[2673]: ---------------------------- MPD announces system playlist update Jul 20 19:46:18 volumio volumio[2673]: info: Ignoring MPD Status Update Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand status took 10 milliseconds Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand clear took 10 milliseconds Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand status took 9 milliseconds Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::parseState Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::parseState Jul 20 19:46:18 volumio volumio[2673]: info: ControllerMpd::pushState Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::servicePushState Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::pushState Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrack 0 Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 19:46:18 volumio volumio[2673]: info: CoreCommandRouter::volumioPushState Jul 20 19:46:18 volumio volumio[2673]: info: CorePlayQueue::getTrack 0 Jul 20 19:46:18 volumio volumio[2673]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 20 19:46:18 volumio volumio[2673]: info: ------------------------------ 30ms Jul 20 19:46:18 volumio volumio[2673]: info: Jul 20 19:46:18 volumio volumio[2673]: ---------------------------- MPD announces system playlist update Jul 20 19:46:18 volumio volumio[2673]: info: Ignoring MPD Status Update Jul 20 19:46:18 volumio volumio[2673]: error: updateQueue error: null Jul 20 19:46:18 volumio volumio[2673]: info: ------------------------------ 28ms Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::parseTrackInfo Jul 20 19:46:18 volumio volumio[2673]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 20 19:46:18 volumio volumio[2673]: info: ------------------------------ 43ms Jul 20 19:46:18 volumio volumio[2673]: info: Jul 20 19:46:18 volumio volumio[2673]: ---------------------------- MPD announces system playlist update Jul 20 19:46:18 volumio volumio[2673]: info: Ignoring MPD Status Update Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497960" took 514 milliseconds Jul 20 19:46:18 volumio volumio[2673]: info: ------------------------------ 496ms Jul 20 19:46:18 volumio volumio[2673]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 20 19:46:18 volumio volumio[2673]: verbose: ControllerMpd::sendMpdCommand play Jul 20 19:46:18 volumio volumio[2673]: info: Jul 20 19:46:18 volumio volumio[2673]: ---------------------------- MPD announces system playlist update Jul 20 19:46:18 volumio volumio[2673]: info: Ignoring MPD Status Update Jul 20 19:46:18 volumio volumio[2673]: info: ------------------------------ 10ms Jul 20 19:46:18 volumio volumio[2673]: info: sendMpdCommand play took 6 milliseconds Jul 20 19:46:18 volumio volumio[2673]: info: ------------------------------ 6ms Jul 20 19:46:20 volumio volumio[2673]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 19:46:20 volumio volumio[2673]: Error: connect ETIMEDOUT 128.121.243.235:80 Jul 20 19:46:20 volumio volumio[2673]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 20 19:46:20 volumio volumio[2673]: errno: -110, Jul 20 19:46:20 volumio volumio[2673]: code: 'ETIMEDOUT', Jul 20 19:46:20 volumio volumio[2673]: syscall: 'connect', Jul 20 19:46:20 volumio volumio[2673]: address: '128.121.243.235', Jul 20 19:46:20 volumio volumio[2673]: port: 80 Jul 20 19:46:20 volumio volumio[2673]: } Jul 20 19:46:20 volumio volumio[2673]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 19:46:20 volumio sudo[2895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-20 19:45 Jul 20 19:46:20 volumio sudo[2895]: 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"