-- Logs begin at Tue 2025-11-04 09:41:46 CST, end at Tue 2025-11-04 09:54:12 CST. -- Nov 04 09:53:05 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioGetState Nov 04 09:53:05 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:07 volumio-x86-784 volumio-time-update[603]: volumio-time-update-util: Date not found in response Nov 04 09:53:07 volumio-x86-784 volumio-time-update[603]: volumio-time-update-util: Retrying in 5 seconds... Nov 04 09:53:12 volumio-x86-784 volumio-time-update[603]: volumio-time-update-util: Fetching time from Volumio... Nov 04 09:53:16 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioGetState Nov 04 09:53:16 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:18 volumio-x86-784 ntpd[2842]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 04 09:53:26 volumio-x86-784 volumio-time-update[603]: volumio-time-update-util: Setting system time to: 2025-11-04 09:53:27 Nov 04 09:53:26 volumio-x86-784 sudo[6094]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-11-04 09:53:27 Nov 04 09:53:26 volumio-x86-784 sudo[6094]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 09:53:26 volumio-x86-784 ntpd[2842]: Soliciting pool server 119.28.206.193 Nov 04 09:53:27 volumio-x86-784 dbus-daemon[601]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.23' (uid=0 pid=6095 comm="timedatectl set-time 2025-11-04 09:53:27 ") Nov 04 09:53:27 volumio-x86-784 systemd[1]: Starting Time & Date Service... Nov 04 09:53:27 volumio-x86-784 ntpd[2842]: Soliciting pool server 162.159.200.1 Nov 04 09:53:27 volumio-x86-784 dbus-daemon[601]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 04 09:53:27 volumio-x86-784 systemd[1]: Started Time & Date Service. Nov 04 09:53:27 volumio-x86-784 systemd-timedated[6096]: Changed local time to Tue Nov 4 09:53:27 2025 Nov 04 09:53:27 volumio-x86-784 sudo[6094]: pam_unix(sudo:session): session closed for user root Nov 04 09:53:27 volumio-x86-784 volumio-time-update[603]: volumio-time-update-util: System time updated successfully. Nov 04 09:53:27 volumio-x86-784 systemd[1]: Started Volumio Time Update Utility. Nov 04 09:53:27 volumio-x86-784 systemd[1]: Reached target Multi-User System. Nov 04 09:53:27 volumio-x86-784 systemd[1]: Reached target Graphical Interface. Nov 04 09:53:27 volumio-x86-784 systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 04 09:53:27 volumio-x86-784 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 04 09:53:27 volumio-x86-784 systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 04 09:53:27 volumio-x86-784 systemd[1]: Startup finished in 4.648s (firmware) + 15.329s (loader) + 11.834s (kernel) + 11min 41.737s (userspace) = 12min 13.549s. Nov 04 09:53:27 volumio-x86-784 ntpd[2842]: Soliciting pool server 108.59.2.24 Nov 04 09:53:27 volumio-x86-784 ntpd[2842]: Soliciting pool server 84.16.73.33 Nov 04 09:53:27 volumio-x86-784 ntpd[2842]: Soliciting pool server 162.159.200.123 Nov 04 09:53:27 volumio-x86-784 ntpd[2842]: Soliciting pool server 139.199.215.251 Nov 04 09:53:27 volumio-x86-784 ntpd[2842]: Soliciting pool server 185.209.85.222 Nov 04 09:53:27 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioGetState Nov 04 09:53:27 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:28 volumio-x86-784 ntpd[2842]: Soliciting pool server 111.230.189.174 Nov 04 09:53:28 volumio-x86-784 ntpd[2842]: Soliciting pool server 193.182.111.142 Nov 04 09:53:28 volumio-x86-784 ntpd[2842]: Soliciting pool server 193.182.111.12 Nov 04 09:53:28 volumio-x86-784 ntpd[2842]: Soliciting pool server 193.182.111.141 Nov 04 09:53:28 volumio-x86-784 ntpd[2842]: Soliciting pool server 116.203.151.74 Nov 04 09:53:28 volumio-x86-784 ntpd[2842]: Soliciting pool server 193.182.111.141 Nov 04 09:53:28 volumio-x86-784 ntpd[2842]: Soliciting pool server 193.182.111.143 Nov 04 09:53:29 volumio-x86-784 ntpd[2842]: Soliciting pool server 5.79.108.34 Nov 04 09:53:29 volumio-x86-784 ntpd[2842]: Soliciting pool server 119.28.183.184 Nov 04 09:53:29 volumio-x86-784 ntpd[2842]: Soliciting pool server 84.16.67.12 Nov 04 09:53:29 volumio-x86-784 ntpd[2842]: Soliciting pool server 84.16.67.12 Nov 04 09:53:29 volumio-x86-784 ntpd[2842]: Soliciting pool server 5.79.108.34 Nov 04 09:53:30 volumio-x86-784 ntpd[2842]: Soliciting pool server 210.28.130.4 Nov 04 09:53:30 volumio-x86-784 ntpd[2842]: Soliciting pool server 139.199.214.202 Nov 04 09:53:30 volumio-x86-784 ntpd[2842]: Soliciting pool server 84.16.73.33 Nov 04 09:53:30 volumio-x86-784 ntpd[2842]: Soliciting pool server 84.16.67.12 Nov 04 09:53:30 volumio-x86-784 ntpd[2842]: Soliciting pool server 193.182.111.14 Nov 04 09:53:31 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:31 volumio-x86-784 volumio[882]: info: TuneIn handleBrowseUri: tunein/local Nov 04 09:53:33 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:33 volumio-x86-784 volumio[882]: info: TuneIn handleBrowseUri: tunein/local Nov 04 09:53:33 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:33 volumio-x86-784 volumio[882]: info: TuneIn handleBrowseUri: tunein/local Nov 04 09:53:35 volumio-x86-784 ntpd[2842]: receive: Unexpected origin timestamp 0xecb3db1e.429f0827 does not match aorg 0000000000.00000000 from server@210.28.130.4 xmt 0xecb3db1f.924c20a8 Nov 04 09:53:35 volumio-x86-784 ntpd[2842]: receive: Unexpected origin timestamp 0xecb3db1e.42a67f60 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xecb3db1f.a1e7ec42 Nov 04 09:53:35 volumio-x86-784 ntpd[2842]: receive: Unexpected origin timestamp 0xecb3db1e.42a898fc does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xecb3db1f.a2826a84 Nov 04 09:53:35 volumio-x86-784 ntpd[2842]: receive: Unexpected origin timestamp 0xecb3db1e.42a454ed does not match aorg 0000000000.00000000 from server@193.182.111.12 xmt 0xecb3db1f.a0c76080 Nov 04 09:53:35 volumio-x86-784 ntpd[2842]: receive: Unexpected origin timestamp 0xecb3db1e.42a78c6d does not match aorg 0000000000.00000000 from server@108.59.2.24 xmt 0xecb3db1f.a6e2cbbe Nov 04 09:53:35 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:35 volumio-x86-784 volumio[882]: info: TuneIn handleBrowseUri: tunein/local Nov 04 09:53:35 volumio-x86-784 ntpd[2842]: receive: Unexpected origin timestamp 0xecb3db1e.42a3203d does not match aorg 0000000000.00000000 from server@5.79.108.34 xmt 0xecb3db1f.a1c1d84a Nov 04 09:53:36 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:36 volumio-x86-784 volumio[882]: info: TuneIn handleBrowseUri: tunein/local Nov 04 09:53:37 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:37 volumio-x86-784 volumio[882]: info: TuneIn handleBrowseUri: tunein/local Nov 04 09:53:37 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:37 volumio-x86-784 volumio[882]: info: TuneIn handleBrowseUri: tunein/local Nov 04 09:53:38 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:38 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:39 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 04 09:53:40 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:40 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioGetState Nov 04 09:53:40 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:40 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:40 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:40 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:40 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:48 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Nov 04 09:53:48 volumio-x86-784 volumio[882]: info: [youtube2-browse] browseUri: youtube2 Nov 04 09:53:48 volumio-x86-784 volumio[882]: info: [youtube2] InnertubeLoader: creating Innertube instance... Nov 04 09:53:51 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioGetState Nov 04 09:53:51 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::ClearQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::stop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::updateTrackBlock Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrackBlock Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::stPlaybackTimer Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::serviceStop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::serviceStop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: ControllerMpd::stop Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand stop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::clearPlayQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::saveQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::addQueueItems Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::addQueueItems Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::saveQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::updateTrackBlock Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrackBlock Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPlay Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::play index 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::stop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::play index undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::startPlaybackTimer Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: [1762221235542] ControllerWebradio::clearAddPlayTrack Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand stop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Nov 04 09:53:55 volumio-x86-784 volumio[882]: ---------------------------- MPD announces state update: player Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: sendMpdCommand stop took 50 milliseconds Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: ControllerMpd::getState Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: sendMpdCommand stop took 10 milliseconds Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand clear Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Nov 04 09:53:55 volumio-x86-784 volumio[882]: ---------------------------- MPD announces system playlist update Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Ignoring MPD Status Update Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: sendMpdCommand status took 6 milliseconds Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: sendMpdCommand clear took 6 milliseconds Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseState Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" Nov 04 09:53:55 volumio-x86-784 volumio[882]: error: Upnp client error: Error: This socket has been ended by the other party Nov 04 09:53:55 volumio-x86-784 volumio[882]: error: updateQueue error: null Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: ------------------------------ 15ms Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:53:55 volumio-x86-784 volumio[882]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: ------------------------------ 27ms Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::ClearQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::stop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::clearPlayQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::saveQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::addQueueItems Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::addQueueItems Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Preload queue cleared Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::saveQueue Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::updateTrackBlock Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrackBlock Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPlay Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::play index 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::stop Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::play index undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CoreStateMachine::startPlaybackTimer Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:53:55 volumio-x86-784 volumio[882]: info: [1762221235734] ControllerWebradio::clearAddPlayTrack Nov 04 09:53:55 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand stop Nov 04 09:53:58 volumio-x86-784 systemd[1]: systemd-timedated.service: Succeeded. Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: Nov 04 09:54:01 volumio-x86-784 volumio[882]: ---------------------------- MPD announces system playlist update Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: Ignoring MPD Status Update Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" took 5868 milliseconds Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: sendMpdCommand stop took 5693 milliseconds Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand play Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand clear Nov 04 09:54:01 volumio-x86-784 volumio[882]: Upnp client error: Error: This socket has been ended by the other party Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: Nov 04 09:54:01 volumio-x86-784 volumio[882]: ---------------------------- MPD announces system playlist update Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: Ignoring MPD Status Update Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: Nov 04 09:54:01 volumio-x86-784 volumio[882]: ---------------------------- MPD announces state update: player Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: ------------------------------ 225ms Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: sendMpdCommand play took 219 milliseconds Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: sendMpdCommand clear took 218 milliseconds Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: ControllerMpd::getState Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" Nov 04 09:54:01 volumio-x86-784 volumio[882]: error: updateQueue error: null Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: ------------------------------ 5ms Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: sendMpdCommand status took 4 milliseconds Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: ControllerMpd::pushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreCommandRouter::servicePushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: CURRENT POSITION 0 Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState stateService stop Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: No code Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: ------------------------------ 41ms Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: Nov 04 09:54:01 volumio-x86-784 volumio[882]: ---------------------------- MPD announces system playlist update Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: Ignoring MPD Status Update Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" took 310 milliseconds Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 04 09:54:01 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand play Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: ------------------------------ 7ms Nov 04 09:54:01 volumio-x86-784 volumio[882]: info: sendMpdCommand play took 5 milliseconds Nov 04 09:54:02 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioGetState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: Nov 04 09:54:03 volumio-x86-784 volumio[882]: ---------------------------- MPD announces state update: player Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ControllerMpd::getState Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: sendMpdCommand status took 56 milliseconds Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseState Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: Nov 04 09:54:03 volumio-x86-784 volumio[882]: ---------------------------- MPD announces state update: player Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ControllerMpd::getState Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: Nov 04 09:54:03 volumio-x86-784 volumio[882]: ---------------------------- MPD announces system playlist update Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: Ignoring MPD Status Update Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: Nov 04 09:54:03 volumio-x86-784 volumio[882]: ---------------------------- MPD announces state update: player Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: sendMpdCommand status took 19 milliseconds Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ControllerMpd::getState Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand status Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseState Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ControllerMpd::pushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::servicePushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream3","artist":"(#1 - 4/3000) ANTENA1 - 94 7 FM","album":null,"uri":"http://5.135.83.159:80/stream3","trackType":""} Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: CURRENT POSITION 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState stateService play Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState currentStatus stop Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ------------------------------ 86ms Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ------------------------------ 8ms Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: sendMpdCommand status took 8 milliseconds Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseState Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ControllerMpd::pushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::servicePushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://5.135.83.159:80/stream3","trackType":""} Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: CURRENT POSITION 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState stateService play Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: Received an update from plugin. extracting info from payload Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ------------------------------ 52ms Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: ControllerMpd::parseTrackInfo Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ControllerMpd::pushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::servicePushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://5.135.83.159:80/stream3","trackType":""} Nov 04 09:54:03 volumio-x86-784 volumio[882]: verbose: CURRENT POSITION 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState stateService play Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::syncState currentStatus play Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: Received an update from plugin. extracting info from payload Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CorePlayQueue::getTrack 0 Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreStateMachine::pushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: CoreCommandRouter::volumioPushState Nov 04 09:54:03 volumio-x86-784 volumio[882]: info: ------------------------------ 51ms Nov 04 09:54:11 volumio-x86-784 volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 09:54:11 volumio-x86-784 volumio[882]: Error: connect ECONNREFUSED 130.211.15.150:443 Nov 04 09:54:11 volumio-x86-784 volumio[882]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 04 09:54:11 volumio-x86-784 volumio[882]: errno: -111, Nov 04 09:54:11 volumio-x86-784 volumio[882]: code: 'ECONNREFUSED', Nov 04 09:54:11 volumio-x86-784 volumio[882]: syscall: 'connect', Nov 04 09:54:11 volumio-x86-784 volumio[882]: address: '130.211.15.150', Nov 04 09:54:11 volumio-x86-784 volumio[882]: port: 443 Nov 04 09:54:11 volumio-x86-784 volumio[882]: } Nov 04 09:54:11 volumio-x86-784 volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 09:54:12 volumio-x86-784 sudo[6292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 09:53 Nov 04 09:54:12 volumio-x86-784 sudo[6292]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"