Oct 28 10:43:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 10:43:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 10:43:02 volumio volumio[1109]: info: Discovery: Getting this device information Oct 28 10:43:02 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 28 10:43:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 10:43:16 volumio dhcpcd[768]: eth0: carrier lost Oct 28 10:43:16 volumio dhcpcd[556]: eth0: carrier lost Oct 28 10:43:16 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 28 10:43:16 volumio dhcpcd[768]: eth0: deleting route to 192.168.1.0/24 Oct 28 10:43:16 volumio dhcpcd[768]: eth0: deleting default route via 192.168.1.1 Oct 28 10:43:16 volumio dhcpcd[556]: eth0: deleting route to 192.168.1.0/24 Oct 28 10:43:16 volumio avahi-daemon[573]: Withdrawing address record for 192.168.1.232 on eth0. Oct 28 10:43:16 volumio avahi-daemon[573]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.232. Oct 28 10:43:16 volumio avahi-daemon[573]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 28 10:43:16 volumio volumio[1109]: info: Discovery: A device disappeared from network Oct 28 10:43:16 volumio volumio[1109]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Oct 28 10:43:16 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 28 10:43:16 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 28 10:43:16 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 28 10:43:16 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 28 10:43:16 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 28 10:43:16 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 28 10:43:16 volumio welcome[3941]: Resolved ip:[0] Oct 28 10:43:16 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 28 10:43:16 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 28 10:43:17 volumio ifplugd(eth0)[902]: Link beat lost. Oct 28 10:43:17 volumio ntpd[874]: IO: Deleting interface #3 eth0, 192.168.1.232#123, interface stats: received=238, sent=246, dropped=1, active_time=985 secs Oct 28 10:43:17 volumio ntpd[874]: PROTO: 124.109.2.169 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 203.159.70.33 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 202.28.92.208 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 147.50.17.138 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 202.28.116.236 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 202.12.97.45 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 171.103.24.86 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 202.29.58.130 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio ntpd[874]: PROTO: 202.80.227.130 unlink local addr 192.168.1.232 -> Oct 28 10:43:17 volumio volumio[1109]: info: Volumio Network Manager: Network status updated: 0 Oct 28 10:43:22 volumio dhcpcd[768]: eth0: carrier acquired Oct 28 10:43:22 volumio dhcpcd[556]: eth0: carrier acquired Oct 28 10:43:22 volumio dhcpcd[556]: eth0: IAID 9e:11:09:35 Oct 28 10:43:22 volumio dhcpcd[768]: eth0: IAID 9e:11:09:35 Oct 28 10:43:22 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Oct 28 10:43:22 volumio dhcpcd[556]: eth0: soliciting an IPv6 router Oct 28 10:43:22 volumio dhcpcd[768]: eth0: rebinding lease of 192.168.1.232 Oct 28 10:43:22 volumio dhcpcd[768]: eth0: probing address 192.168.1.232/24 Oct 28 10:43:23 volumio dhcpcd[556]: eth0: rebinding lease of 192.168.1.232 Oct 28 10:43:23 volumio ifplugd(eth0)[902]: Link beat detected. Oct 28 10:43:23 volumio dhcpcd[556]: eth0: probing address 192.168.1.232/24 Oct 28 10:43:23 volumio dhcpcd[768]: eth0: soliciting an IPv6 router Oct 28 10:43:27 volumio dhcpcd[556]: eth0: leased 192.168.1.232 for 86400 seconds Oct 28 10:43:27 volumio avahi-daemon[573]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.232. Oct 28 10:43:27 volumio avahi-daemon[573]: New relevant interface eth0.IPv4 for mDNS. Oct 28 10:43:27 volumio avahi-daemon[573]: Registering new address record for 192.168.1.232 on eth0.IPv4. Oct 28 10:43:27 volumio dhcpcd[556]: eth0: adding route to 192.168.1.0/24 Oct 28 10:43:27 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 28 10:43:27 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 28 10:43:27 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 28 10:43:27 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 28 10:43:27 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 28 10:43:27 volumio dhcpcd[556]: eth0: adding default route via 192.168.1.1 Oct 28 10:43:27 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 28 10:43:27 volumio welcome[3998]: Resolved ip:[1] 192.168.1.232 Oct 28 10:43:27 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 28 10:43:27 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 28 10:43:28 volumio dhcpcd[768]: eth0: leased 192.168.1.232 for 86400 seconds Oct 28 10:43:28 volumio dhcpcd[768]: eth0: adding route to 192.168.1.0/24 Oct 28 10:43:28 volumio dhcpcd[768]: eth0: adding default route via 192.168.1.1 Oct 28 10:43:28 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 28 10:43:28 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 28 10:43:28 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 28 10:43:28 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 28 10:43:28 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 28 10:43:28 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 28 10:43:28 volumio welcome[4018]: Resolved ip:[1] 192.168.1.232 Oct 28 10:43:28 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 28 10:43:28 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 28 10:43:28 volumio volumio[1109]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Oct 28 10:43:29 volumio ntpd[874]: IO: Listen normally on 4 eth0 192.168.1.232:123 Oct 28 10:43:29 volumio ntpd[874]: IO: new interface(s) found: waking up resolver Oct 28 10:43:34 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 28 10:43:34 volumio volumio[1109]: info: Preload queue cleared Oct 28 10:43:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 28 10:43:36 volumio volumio[1109]: info: Getting webradio selection Oct 28 10:43:36 volumio volumio[1109]: info: Preload queue cleared Oct 28 10:43:37 volumio volumio[1109]: info: Volumio Network Manager: Network status updated: 1 Oct 28 10:43:38 volumio volumio[1109]: info: Preload queue cleared Oct 28 10:43:38 volumio volumio[1109]: info: CoreCommandRouter::volumioAddQueueItems Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::addQueueItems Oct 28 10:43:38 volumio volumio[1109]: info: CorePlayQueue::addQueueItems Oct 28 10:43:38 volumio volumio[1109]: info: Preload queue cleared Oct 28 10:43:38 volumio volumio[1109]: info: Adding Item to queue: http://media-ice.musicradio.com/ClassicFMMP3 Oct 28 10:43:38 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 28 10:43:38 volumio volumio[1109]: info: CoreCommandRouter::volumioPushQueue Oct 28 10:43:38 volumio volumio[1109]: info: CorePlayQueue::saveQueue Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::updateTrackBlock Oct 28 10:43:38 volumio volumio[1109]: info: CorePlayQueue::getTrackBlock Oct 28 10:43:38 volumio volumio[1109]: info: CoreCommandRouter::volumioPlay Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::play index 3 Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::stop Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::play index undefined Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 10:43:38 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::startPlaybackTimer Oct 28 10:43:38 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:38 volumio volumio[1109]: info: [1761623018297] ControllerWebradio::clearAddPlayTrack Oct 28 10:43:38 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 10:43:38 volumio mpd[1478]: player: played "https://media.onair.one/8200/4/" Oct 28 10:43:38 volumio volumio[1109]: info: sendMpdCommand stop took 11 milliseconds Oct 28 10:43:38 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 10:43:38 volumio volumio[1109]: info: Oct 28 10:43:38 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:38 volumio volumio[1109]: info: Oct 28 10:43:38 volumio volumio[1109]: ---------------------------- MPD announces system playlist update Oct 28 10:43:38 volumio volumio[1109]: info: Ignoring MPD Status Update Oct 28 10:43:38 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:38 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:38 volumio volumio[1109]: info: sendMpdCommand clear took 1 milliseconds Oct 28 10:43:38 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/ClassicFMMP3" Oct 28 10:43:38 volumio volumio[1109]: info: Oct 28 10:43:38 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:38 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:38 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:38 volumio volumio[1109]: info: Oct 28 10:43:38 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:38 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:38 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:38 volumio volumio[1109]: error: updateQueue error: null Oct 28 10:43:38 volumio volumio[1109]: info: sendMpdCommand status took 3 milliseconds Oct 28 10:43:38 volumio volumio[1109]: info: ------------------------------ 4ms Oct 28 10:43:38 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:38 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:38 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:38 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:38 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:38 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:43:38 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:38 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:38 volumio volumio[1109]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 28 10:43:38 volumio volumio[1109]: info: ------------------------------ 14ms Oct 28 10:43:39 volumio mpd[1478]: exception: No such playlist Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand add "http://media-ice.musicradio.com/ClassicFMMP3" Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces system playlist update Oct 28 10:43:39 volumio volumio[1109]: info: Ignoring MPD Status Update Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 779 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 779 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand add "http://media-ice.musicradio.com/ClassicFMMP3" took 2 milliseconds Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand play Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: 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 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: No code Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: 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 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: No code Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 832ms Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 832ms Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces system playlist update Oct 28 10:43:39 volumio volumio[1109]: info: Ignoring MPD Status Update Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces system playlist update Oct 28 10:43:39 volumio volumio[1109]: info: Ignoring MPD Status Update Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 56ms Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand play took 55 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 3ms Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 2ms Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces system playlist update Oct 28 10:43:39 volumio volumio[1109]: info: Ignoring MPD Status Update Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces system playlist update Oct 28 10:43:39 volumio volumio[1109]: info: Ignoring MPD Status Update Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 21ms Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 21ms Oct 28 10:43:39 volumio mpd[1478]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Oct 28 10:43:39 volumio mpd[1478]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:39 volumio mpd[1478]: player: problems opening audio device while playing "http://media-ice.musicradio.com/ClassicFMMP3" Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 10 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 11 milliseconds Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 11 milliseconds Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 10:43:39 volumio volumio[1109]: info: Oct 28 10:43:39 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::getState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 26 milliseconds Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand clearerror took 28 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 29 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand clearerror took 28 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand clearerror took 28 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand status took 17 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand clearerror took 10 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseTrackInfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseTrackInfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseTrackInfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseState Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseTrackInfo Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: verbose: STATE SERVICE {"status":"pause","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":"ClassicFMMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMMP3","trackType":""} Oct 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService pause Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: verbose: STATE SERVICE {"status":"pause","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":"ClassicFMMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMMP3","trackType":""} Oct 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService pause Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: verbose: STATE SERVICE {"status":"pause","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":"ClassicFMMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMMP3","trackType":""} Oct 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService pause Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: verbose: STATE SERVICE {"status":"pause","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":"ClassicFMMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMMP3","trackType":""} Oct 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService pause Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 83ms Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 82ms Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 82ms Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 79ms Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 28 10:43:39 volumio volumio[1109]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseTrackInfo Oct 28 10:43:39 volumio volumio[1109]: verbose: ControllerMpd::parseTrackInfo Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: verbose: STATE SERVICE {"status":"pause","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":"ClassicFMMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMMP3","trackType":""} Oct 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService pause Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ControllerMpd::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::servicePushState Oct 28 10:43:39 volumio volumio[1109]: info: CorePlayQueue::getTrack 3 Oct 28 10:43:39 volumio volumio[1109]: verbose: STATE SERVICE {"status":"pause","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":"ClassicFMMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMMP3","trackType":""} Oct 28 10:43:39 volumio volumio[1109]: verbose: CURRENT POSITION 3 Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState stateService pause Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::syncState currentStatus stop Oct 28 10:43:39 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 28 10:43:39 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 100ms Oct 28 10:43:39 volumio volumio[1109]: info: ------------------------------ 84ms Oct 28 10:44:04 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 10:44:04 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 10:44:04 volumio volumio[1109]: info: Discovery: Getting this device information Oct 28 10:44:04 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 28 10:44:04 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 10:44:06 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 28 10:44:06 volumio volumio[1109]: info: CURURI: music-library Oct 28 10:44:06 volumio mpd[1478]: exception: No database Oct 28 10:44:06 volumio volumio[1109]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Oct 28 10:44:06 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 10:44:06 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 10:44:06 volumio volumio[1109]: info: Discovery: Getting this device information Oct 28 10:44:06 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 28 10:44:06 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 10:44:06 volumio volumio[1109]: info: Preload queue cleared Oct 28 10:44:07 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 10:44:07 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 10:44:07 volumio volumio[1109]: info: Discovery: Getting this device information Oct 28 10:44:07 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 28 10:44:07 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 10:44:09 volumio volumio[1109]: verbose: New Socket.io Connection to 192.168.1.232:3000 from 192.168.1.45 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 28 10:44:09 volumio volumio[1109]: verbose: New Socket.io Connection to 192.168.1.232:3000 from 192.168.1.45 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 10:44:29 volumio volumio[1109]: info: Retrieving Cloud Streaming UI Oct 28 10:44:29 volumio volumio[1109]: info: Getting Tidal Cloud Configuration Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 10:44:29 volumio volumio[1109]: info: Getting Qobuz Cloud Configuration Oct 28 10:44:29 volumio volumio[1109]: info: Asking plugin for UI Config Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 10:44:29 volumio volumio[1109]: info: Getting Spotify Cloud Configuration Oct 28 10:44:29 volumio volumio[1109]: info: Asking plugin for UI Config Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 10:44:29 volumio volumio[1109]: info: Saving Spotify Acccount Oct 28 10:44:29 volumio volumio[1109]: info: Got Tidal Cloud Configuration Oct 28 10:44:29 volumio volumio[1109]: info: Got it Oct 28 10:44:29 volumio volumio[1109]: info: Got it Oct 28 10:44:29 volumio volumio[1109]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 10:44:29 volumio volumio[1109]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:44:29 volumio mpd[1478]: exception: No database Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 28 10:44:29 volumio volumio[1109]: info: Discovery: Getting this device information Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 28 10:44:29 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 28 10:44:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 28 10:44:33 volumio mpd[1478]: exception: No database Oct 28 10:44:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 28 10:44:33 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 28 10:44:33 volumio volumio[1109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 10:44:33 volumio volumio[1109]: Error: getaddrinfo -3007 Oct 28 10:44:33 volumio volumio[1109]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Oct 28 10:44:33 volumio volumio[1109]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Oct 28 10:44:33 volumio volumio[1109]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Oct 28 10:44:33 volumio volumio[1109]: code: -3007, Oct 28 10:44:33 volumio volumio[1109]: errno: -3007, Oct 28 10:44:33 volumio volumio[1109]: syscall: 'getaddrinfo' Oct 28 10:44:33 volumio volumio[1109]: } Oct 28 10:44:33 volumio volumio[1109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 10:44:34 volumio sudo[4158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 10:43' Oct 28 10:44:34 volumio sudo[4158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"