-- Logs begin at Tue 2024-09-17 15:32:23 UTC, end at Wed 2024-10-09 20:04:02 UTC. -- Oct 09 20:03:02 volumio dhcpcd[959]: wlan0: NAK: Invalid Request Message from 192.168.3.1 Oct 09 20:03:02 volumio dhcpcd[959]: wlan0: message: Invalid Request Message Oct 09 20:03:03 volumio dhcpcd[959]: wlan0: soliciting a DHCP lease Oct 09 20:03:04 volumio nmbd[890]: [2024/10/09 20:03:04.957235, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 09 20:03:04 volumio nmbd[890]: Packet send failed to 192.168.3.255(138) ERRNO=Network is unreachable Oct 09 20:03:09 volumio nmbd[890]: [2024/10/09 20:03:09.981855, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 09 20:03:09 volumio nmbd[890]: Packet send failed to 192.168.3.255(138) ERRNO=Network is unreachable Oct 09 20:03:13 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::stPlaybackTimer Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::serviceStop Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::serviceStop Oct 09 20:03:13 volumio volumio[1081]: info: [1728504193505] ControllerWebradio::stop Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 09 20:03:13 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:13 volumio volumio[1081]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599226 Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 09 20:03:13 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::play index 0 Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::play index undefined Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:13 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Oct 09 20:03:13 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:13 volumio volumio[1081]: info: [1728504193527] ControllerWebradio::clearAddPlayTrack Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 20:03:13 volumio volumio[1081]: info: Oct 09 20:03:13 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:13 volumio volumio[1081]: info: sendMpdCommand stop took 100 milliseconds Oct 09 20:03:13 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:13 volumio volumio[1081]: info: sendMpdCommand stop took 80 milliseconds Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Oct 09 20:03:13 volumio volumio[1081]: info: Oct 09 20:03:13 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:13 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:13 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Oct 09 20:03:13 volumio volumio[1081]: info: sendMpdCommand clear took 3 milliseconds Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599226" Oct 09 20:03:13 volumio volumio[1081]: error: updateQueue error: null Oct 09 20:03:13 volumio volumio[1081]: info: ------------------------------ 8ms Oct 09 20:03:13 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 09 20:03:13 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:13 volumio volumio[1081]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 09 20:03:13 volumio volumio[1081]: info: ------------------------------ 14ms Oct 09 20:03:14 volumio ntpd[3453]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 09 20:03:15 volumio dhcpcd[959]: wlan0: offered 192.168.3.37 from 192.168.3.1 Oct 09 20:03:19 volumio dhcpcd[959]: wlan0: probing address 192.168.3.37/24 Oct 09 20:03:22 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:22 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:22 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 09 20:03:22 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:22 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 09 20:03:22 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 09 20:03:22 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:22 volumio volumio[1081]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580836 Oct 09 20:03:22 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 09 20:03:22 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:22 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 09 20:03:22 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 09 20:03:22 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::play index 0 Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::play index undefined Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:22 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:22 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Oct 09 20:03:22 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:22 volumio volumio[1081]: info: [1728504202829] ControllerWebradio::clearAddPlayTrack Oct 09 20:03:22 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 20:03:25 volumio dhcpcd[959]: wlan0: leased 192.168.3.37 for 86400 seconds Oct 09 20:03:25 volumio dhcpcd[959]: wlan0: adding route to 192.168.3.0/24 Oct 09 20:03:25 volumio avahi-daemon[856]: Registering new address record for 192.168.3.37 on wlan0.IPv4. Oct 09 20:03:25 volumio dhcpcd[959]: wlan0: changing default route via 192.168.3.1 Oct 09 20:03:25 volumio dhcpcd[959]: wlan0: dhcp_envoption 213: Operation not supported Oct 09 20:03:25 volumio dhcpcd[959]: wlan0: dhcp_envoption 213: Operation not supported Oct 09 20:03:25 volumio ntpd[3453]: ntpd exiting on signal 15 (Terminated) Oct 09 20:03:25 volumio ntpd[3453]: 82.142.168.18 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio systemd[1]: Stopping Network Time Service... Oct 09 20:03:25 volumio ntpd[3453]: 46.188.16.150 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio systemd[1]: ntp.service: Succeeded. Oct 09 20:03:25 volumio ntpd[3453]: 188.225.9.167 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 162.159.200.1 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 213.33.141.134 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio systemd[1]: Stopped Network Time Service. Oct 09 20:03:25 volumio ntpd[3453]: 94.131.113.113 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 213.234.203.30 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 91.207.136.55 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 192.36.143.130 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 85.21.78.23 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 176.215.178.239 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio ntpd[3453]: 162.159.200.123 local addr 169.254.77.212 -> Oct 09 20:03:25 volumio systemd[1]: Starting Network Time Service... Oct 09 20:03:25 volumio ntpd[3852]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 09 20:03:25 volumio ntpd[3852]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 09 20:03:25 volumio systemd[1]: Started Network Time Service. Oct 09 20:03:25 volumio ntpd[3858]: proto: precision = 0.264 usec (-22) Oct 09 20:03:25 volumio ntpd[3858]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 09 20:03:25 volumio ntpd[3858]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 09 20:03:25 volumio ntpd[3858]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 652 days ago Oct 09 20:03:25 volumio dhcpcd[959]: wlan0: deleting route to 169.254.0.0/16 Oct 09 20:03:25 volumio ntpd[3858]: Listen and drop on 0 v6wildcard [::]:123 Oct 09 20:03:25 volumio ntpd[3858]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 09 20:03:25 volumio avahi-daemon[856]: Withdrawing address record for 169.254.77.212 on wlan0. Oct 09 20:03:25 volumio avahi-daemon[856]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.77.212. Oct 09 20:03:25 volumio ntpd[3858]: Listen normally on 2 lo 127.0.0.1:123 Oct 09 20:03:25 volumio avahi-daemon[856]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.37. Oct 09 20:03:25 volumio ntpd[3858]: Listen normally on 3 wlan0 192.168.3.37:123 Oct 09 20:03:25 volumio ntpd[3858]: Listening on routing socket on fd #20 for interface updates Oct 09 20:03:25 volumio ntpd[3858]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 09 20:03:25 volumio ntpd[3858]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 09 20:03:26 volumio ntpd[3858]: Soliciting pool server 192.36.143.130 Oct 09 20:03:28 volumio ntpd[3858]: Soliciting pool server 91.206.16.3 Oct 09 20:03:28 volumio ntpd[3858]: Soliciting pool server 94.131.113.113 Oct 09 20:03:29 volumio volumio[1081]: info: Discovery: adding 0204848b-3805-4aa0-b002-29adc3e58bfa Oct 09 20:03:29 volumio volumio[1081]: info: Discovery: Found device Volumio Oct 09 20:03:29 volumio volumio[1081]: info: CoreCommandRouter::volumioGetState Oct 09 20:03:29 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:29 volumio volumio[1081]: info: Oct 09 20:03:29 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:29 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:29 volumio volumio[1081]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599226" took 15690 milliseconds Oct 09 20:03:29 volumio volumio[1081]: info: sendMpdCommand stop took 6471 milliseconds Oct 09 20:03:29 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 09 20:03:29 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Oct 09 20:03:29 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Oct 09 20:03:29 volumio volumio[1081]: info: ------------------------------ 11ms Oct 09 20:03:29 volumio volumio[1081]: info: sendMpdCommand play took 9 milliseconds Oct 09 20:03:29 volumio volumio[1081]: info: Oct 09 20:03:29 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:29 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:29 volumio volumio[1081]: info: Oct 09 20:03:29 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:29 volumio volumio[1081]: info: sendMpdCommand clear took 11 milliseconds Oct 09 20:03:29 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:29 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:29 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580836" Oct 09 20:03:29 volumio volumio[1081]: error: updateQueue error: null Oct 09 20:03:29 volumio volumio[1081]: info: Oct 09 20:03:29 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:29 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:29 volumio volumio[1081]: info: ------------------------------ 148ms Oct 09 20:03:29 volumio volumio[1081]: info: sendMpdCommand status took 146 milliseconds Oct 09 20:03:29 volumio volumio[1081]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580836" took 145 milliseconds Oct 09 20:03:29 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:29 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 09 20:03:29 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Oct 09 20:03:29 volumio volumio[1081]: info: ControllerMpd::pushState Oct 09 20:03:29 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 09 20:03:29 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:29 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 20:03:29 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:29 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:29 volumio volumio[1081]: 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} Oct 09 20:03:29 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 09 20:03:29 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop Oct 09 20:03:29 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 09 20:03:29 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:29 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:29 volumio volumio[1081]: info: No code Oct 09 20:03:29 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:29 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:29 volumio volumio[1081]: info: ------------------------------ 197ms Oct 09 20:03:29 volumio volumio[1081]: info: ------------------------------ 53ms Oct 09 20:03:29 volumio volumio[1081]: info: sendMpdCommand play took 50 milliseconds Oct 09 20:03:29 volumio ntpd[3858]: Soliciting pool server 162.159.200.1 Oct 09 20:03:30 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:30 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:30 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 09 20:03:30 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:30 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 09 20:03:30 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 09 20:03:30 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:30 volumio volumio[1081]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519866 Oct 09 20:03:30 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 09 20:03:30 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:30 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 09 20:03:30 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 09 20:03:30 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::play index 0 Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::play index undefined Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:30 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:30 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Oct 09 20:03:30 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:30 volumio volumio[1081]: info: [1728504210127] ControllerWebradio::clearAddPlayTrack Oct 09 20:03:30 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 20:03:31 volumio volumio[1081]: info: Oct 09 20:03:31 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:31 volumio volumio[1081]: info: sendMpdCommand stop took 1523 milliseconds Oct 09 20:03:31 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:31 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:31 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Oct 09 20:03:31 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Oct 09 20:03:31 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:31 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:31 volumio volumio[1081]: info: Oct 09 20:03:31 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:31 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:31 volumio volumio[1081]: info: sendMpdCommand clear took 9 milliseconds Oct 09 20:03:31 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 09 20:03:31 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519866" Oct 09 20:03:31 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:31 volumio volumio[1081]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 09 20:03:31 volumio volumio[1081]: info: ------------------------------ 19ms Oct 09 20:03:31 volumio volumio[1081]: error: updateQueue error: null Oct 09 20:03:31 volumio volumio[1081]: info: ------------------------------ 11ms Oct 09 20:03:31 volumio volumio[1081]: info: Oct 09 20:03:31 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:31 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:31 volumio volumio[1081]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519866" took 144 milliseconds Oct 09 20:03:31 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 09 20:03:31 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Oct 09 20:03:31 volumio volumio[1081]: info: ------------------------------ 8ms Oct 09 20:03:31 volumio volumio[1081]: info: sendMpdCommand play took 4 milliseconds Oct 09 20:03:32 volumio volumio[1081]: info: Oct 09 20:03:32 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:32 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:32 volumio volumio[1081]: info: Oct 09 20:03:32 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:32 volumio volumio[1081]: info: sendMpdCommand status took 320 milliseconds Oct 09 20:03:32 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:32 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:32 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 09 20:03:32 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:32 volumio volumio[1081]: info: ControllerMpd::pushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 09 20:03:32 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:32 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 3/8000) CALMRADIO.COM - Bachata","album":null,"uri":"http://23.82.11.89:9828/stream","trackType":""} Oct 09 20:03:32 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 09 20:03:32 volumio volumio[1081]: info: ControllerMpd::pushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 09 20:03:32 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:32 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 3/8000) CALMRADIO.COM - Bachata","album":null,"uri":"http://23.82.11.89:9828/stream","trackType":""} Oct 09 20:03:32 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 09 20:03:32 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:32 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:32 volumio volumio[1081]: info: ------------------------------ 373ms Oct 09 20:03:32 volumio volumio[1081]: info: ------------------------------ 70ms Oct 09 20:03:32 volumio volumio[1081]: info: Oct 09 20:03:32 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:32 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:32 volumio volumio[1081]: info: Oct 09 20:03:32 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:32 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:32 volumio volumio[1081]: info: ------------------------------ 3ms Oct 09 20:03:32 volumio volumio[1081]: info: sendMpdCommand status took 2 milliseconds Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:32 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 09 20:03:32 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:32 volumio volumio[1081]: info: ControllerMpd::pushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 09 20:03:32 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:32 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Various Artists - Los Mejores De La Bachata - El Gringo De La Bachata","artist":"CALMRADIO.COM - Bachata","album":null,"uri":"http://23.82.11.89:9828/stream","trackType":""} Oct 09 20:03:32 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 09 20:03:32 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:32 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:32 volumio volumio[1081]: info: ------------------------------ 23ms Oct 09 20:03:37 volumio dhcpcd[959]: wlan0: carrier lost Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: CTRL-EVENT-DISCONNECTED bssid=50:ff:20:30:7b:1c reason=0 locally_generated=1 Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=RU Oct 09 20:03:37 volumio avahi-daemon[856]: Withdrawing address record for 192.168.3.37 on wlan0. Oct 09 20:03:37 volumio avahi-daemon[856]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.37. Oct 09 20:03:37 volumio volumio[1081]: info: Discovery: A device disappeared from network Oct 09 20:03:37 volumio volumio[1081]: info: Discovery: Device volumio disappeared from network Oct 09 20:03:37 volumio avahi-daemon[856]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 09 20:03:37 volumio dhcpcd[959]: wlan0: deleting route to 192.168.3.0/24 Oct 09 20:03:37 volumio dhcpcd[959]: wlan0: deleting default route via 192.168.3.1 Oct 09 20:03:37 volumio dhcpcd[959]: wlan0: dhcp_envoption 213: Operation not supported Oct 09 20:03:37 volumio dhcpcd[959]: wlan0: dhcp_envoption 213: Operation not supported Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: Trying to associate with 90:98:38:52:79:b4 (SSID='HUAWEI-104EM3' freq=2412 MHz) Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: Associated with 90:98:38:52:79:b4 Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: WPA: Key negotiation completed with 90:98:38:52:79:b4 [PTK=CCMP GTK=CCMP] Oct 09 20:03:37 volumio wpa_supplicant[2616]: wlan0: CTRL-EVENT-CONNECTED - Connection to 90:98:38:52:79:b4 completed [id=0 id_str=] Oct 09 20:03:37 volumio dhcpcd[959]: wlan0: carrier acquired Oct 09 20:03:37 volumio dhcpcd[959]: wlan0: IAID d9:fd:b4:38 Oct 09 20:03:38 volumio dhcpcd[959]: wlan0: soliciting an IPv6 router Oct 09 20:03:38 volumio dhcpcd[959]: wlan0: rebinding lease of 192.168.3.37 Oct 09 20:03:38 volumio dhcpcd[959]: wlan0: NAK: Invalid Request Message from 192.168.3.1 Oct 09 20:03:38 volumio dhcpcd[959]: wlan0: message: Invalid Request Message Oct 09 20:03:38 volumio dhcpcd[959]: wlan0: soliciting a DHCP lease Oct 09 20:03:38 volumio ntpd[3858]: Deleting interface #3 wlan0, 192.168.3.37#123, interface stats: received=0, sent=4, dropped=0, active_time=13 secs Oct 09 20:03:40 volumio dhcpcd[959]: wlan0: offered 192.168.3.37 from 192.168.3.1 Oct 09 20:03:40 volumio dhcpcd[959]: wlan0: probing address 192.168.3.37/24 Oct 09 20:03:45 volumio dhcpcd[959]: wlan0: leased 192.168.3.37 for 86400 seconds Oct 09 20:03:45 volumio dhcpcd[959]: wlan0: adding route to 192.168.3.0/24 Oct 09 20:03:45 volumio avahi-daemon[856]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.37. Oct 09 20:03:45 volumio avahi-daemon[856]: New relevant interface wlan0.IPv4 for mDNS. Oct 09 20:03:45 volumio dhcpcd[959]: wlan0: adding default route via 192.168.3.1 Oct 09 20:03:45 volumio avahi-daemon[856]: Registering new address record for 192.168.3.37 on wlan0.IPv4. Oct 09 20:03:45 volumio dhcpcd[959]: wlan0: dhcp_envoption 213: Operation not supported Oct 09 20:03:45 volumio dhcpcd[959]: wlan0: dhcp_envoption 213: Operation not supported Oct 09 20:03:45 volumio ntpd[3858]: ntpd exiting on signal 15 (Terminated) Oct 09 20:03:45 volumio systemd[1]: Stopping Network Time Service... Oct 09 20:03:45 volumio systemd[1]: ntp.service: Succeeded. Oct 09 20:03:45 volumio systemd[1]: Stopped Network Time Service. Oct 09 20:03:45 volumio systemd[1]: Starting Network Time Service... Oct 09 20:03:45 volumio volumio[1081]: verbose: New Socket.io Connection to volumio.local from 192.168.3.8 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 09 20:03:45 volumio volumio[1081]: info: CoreCommandRouter::volumioGetState Oct 09 20:03:45 volumio ntpd[3962]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 09 20:03:45 volumio ntpd[3962]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 09 20:03:45 volumio systemd[1]: Started Network Time Service. Oct 09 20:03:45 volumio ntpd[3969]: proto: precision = 0.252 usec (-22) Oct 09 20:03:45 volumio ntpd[3969]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 09 20:03:45 volumio ntpd[3969]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 09 20:03:45 volumio ntpd[3969]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 652 days ago Oct 09 20:03:45 volumio ntpd[3969]: Listen and drop on 0 v6wildcard [::]:123 Oct 09 20:03:45 volumio ntpd[3969]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 09 20:03:45 volumio ntpd[3969]: Listen normally on 2 lo 127.0.0.1:123 Oct 09 20:03:45 volumio ntpd[3969]: Listen normally on 3 wlan0 192.168.3.37:123 Oct 09 20:03:45 volumio ntpd[3969]: Listening on routing socket on fd #20 for interface updates Oct 09 20:03:45 volumio ntpd[3969]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 09 20:03:45 volumio ntpd[3969]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 09 20:03:45 volumio volumio[1081]: info: CoreCommandRouter::volumioGetQueue Oct 09 20:03:45 volumio volumio[1081]: info: CoreStateMachine::getQueue Oct 09 20:03:45 volumio volumio[1081]: info: CorePlayQueue::getQueue Oct 09 20:03:45 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 09 20:03:45 volumio volumio[1081]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Oct 09 20:03:45 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Oct 09 20:03:45 volumio volumio[1081]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Oct 09 20:03:45 volumio volumio[1081]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Oct 09 20:03:45 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Oct 09 20:03:46 volumio volumio[1081]: info: Discovery: adding 0204848b-3805-4aa0-b002-29adc3e58bfa Oct 09 20:03:46 volumio volumio[1081]: info: Discovery: Found device Volumio Oct 09 20:03:46 volumio volumio[1081]: info: CoreCommandRouter::volumioGetState Oct 09 20:03:46 volumio ntpd[3969]: Soliciting pool server 192.36.143.130 Oct 09 20:03:47 volumio ntpd[3969]: Soliciting pool server 95.31.7.160 Oct 09 20:03:47 volumio ntpd[3969]: Soliciting pool server 91.206.16.3 Oct 09 20:03:48 volumio ntpd[3969]: Soliciting pool server 185.209.85.222 Oct 09 20:03:48 volumio ntpd[3969]: Soliciting pool server 94.131.113.113 Oct 09 20:03:49 volumio ntpd[3969]: Soliciting pool server 46.188.16.150 Oct 09 20:03:49 volumio ntpd[3969]: Soliciting pool server 162.159.200.1 Oct 09 20:03:49 volumio ntpd[3969]: Soliciting pool server 162.159.200.1 Oct 09 20:03:50 volumio ntpd[3969]: Soliciting pool server 213.234.203.30 Oct 09 20:03:51 volumio ntpd[3969]: Soliciting pool server 162.159.200.123 Oct 09 20:03:52 volumio ntpd[3969]: Soliciting pool server 2a00:18c0:1:1::5 Oct 09 20:03:56 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::stPlaybackTimer Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::serviceStop Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::serviceStop Oct 09 20:03:56 volumio volumio[1081]: info: [1728504236014] ControllerWebradio::stop Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Oct 09 20:03:56 volumio volumio[1081]: info: Preload queue cleared Oct 09 20:03:56 volumio volumio[1081]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599226 Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::saveQueue Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::play index 0 Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::stop Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::play index undefined Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:56 volumio volumio[1081]: info: [1728504236041] ControllerWebradio::clearAddPlayTrack Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 20:03:56 volumio volumio[1081]: info: Oct 09 20:03:56 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand stop took 31 milliseconds Oct 09 20:03:56 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand stop took 4 milliseconds Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Oct 09 20:03:56 volumio volumio[1081]: info: Oct 09 20:03:56 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:56 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand status took 2 milliseconds Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand clear took 2 milliseconds Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599226" Oct 09 20:03:56 volumio volumio[1081]: error: updateQueue error: null Oct 09 20:03:56 volumio volumio[1081]: info: ------------------------------ 3ms Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:56 volumio volumio[1081]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 09 20:03:56 volumio volumio[1081]: info: ------------------------------ 7ms Oct 09 20:03:56 volumio volumio[1081]: info: Oct 09 20:03:56 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:56 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599226" took 136 milliseconds Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Oct 09 20:03:56 volumio volumio[1081]: info: ------------------------------ 6ms Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand play took 4 milliseconds Oct 09 20:03:56 volumio volumio[1081]: info: Oct 09 20:03:56 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:56 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:56 volumio volumio[1081]: info: Oct 09 20:03:56 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Oct 09 20:03:56 volumio volumio[1081]: info: Ignoring MPD Status Update Oct 09 20:03:56 volumio volumio[1081]: info: Oct 09 20:03:56 volumio volumio[1081]: ---------------------------- MPD announces state update: player Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand status took 28 milliseconds Oct 09 20:03:56 volumio volumio[1081]: info: ControllerMpd::getState Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:56 volumio volumio[1081]: info: ------------------------------ 8ms Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::parseState Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:56 volumio volumio[1081]: info: ControllerMpd::pushState Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:56 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Joe Veras - Desde Que Me Dejaste","artist":"Bachata Hit Radio","album":null,"uri":"http://217.23.15.43:9599/stream","trackType":""} Oct 09 20:03:56 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Oct 09 20:03:56 volumio volumio[1081]: info: ------------------------------ 40ms Oct 09 20:03:56 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 09 20:03:56 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Oct 09 20:03:56 volumio volumio[1081]: info: ControllerMpd::pushState Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Oct 09 20:03:56 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Oct 09 20:03:56 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","position":0,"seek":602,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"126 Kbps","isStreaming":false,"title":"Joe Veras - Desde Que Me Dejaste","artist":"Bachata Hit Radio","album":null,"uri":"http://217.23.15.43:9599/stream","trackType":""} Oct 09 20:03:56 volumio volumio[1081]: verbose: CURRENT POSITION 0 Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus play Oct 09 20:03:56 volumio volumio[1081]: info: Received an update from plugin. extracting info from payload Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:56 volumio volumio[1081]: info: CoreStateMachine::pushState Oct 09 20:03:56 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Oct 09 20:03:56 volumio volumio[1081]: info: ------------------------------ 47ms Oct 09 20:04:01 volumio volumio[1081]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 20:04:01 volumio volumio[1081]: Error: connect ENETUNREACH 151.101.38.79:80 Oct 09 20:04:01 volumio volumio[1081]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 09 20:04:01 volumio volumio[1081]: errno: -101, Oct 09 20:04:01 volumio volumio[1081]: code: 'ENETUNREACH', Oct 09 20:04:01 volumio volumio[1081]: syscall: 'connect', Oct 09 20:04:01 volumio volumio[1081]: address: '151.101.38.79', Oct 09 20:04:01 volumio volumio[1081]: port: 80 Oct 09 20:04:01 volumio volumio[1081]: } Oct 09 20:04:01 volumio volumio[1081]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 20:04:02 volumio sudo[3988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-09 20:03 Oct 09 20:04:02 volumio sudo[3988]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"