Feb 26 16:27:03 volumio kernel: wlan0: deauthenticating from 0a:55:31:03:72:ae by local choice (Reason: 3=DEAUTH_LEAVING) Feb 26 16:27:03 volumio dhcpcd[759]: wlan0: carrier lost Feb 26 16:27:04 volumio wpa_supplicant[9593]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Feb 26 16:27:04 volumio wpa_supplicant[9593]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=RU Feb 26 16:27:04 volumio avahi-daemon[679]: Withdrawing address record for 192.168.50.11 on wlan0. Feb 26 16:27:04 volumio dhcpcd[759]: wlan0: deleting route to 192.168.50.0/24 Feb 26 16:27:04 volumio volumio[1232]: info: Discovery: A device disappeared from network Feb 26 16:27:04 volumio volumio[1232]: info: Discovery: Device volumio disappeared from network Feb 26 16:27:04 volumio volumio[1232]: info: Discovery: A device disappeared from network Feb 26 16:27:04 volumio volumio[1232]: info: Discovery: A device disappeared from network Feb 26 16:27:04 volumio volumio[1232]: info: Discovery: A device disappeared from network Feb 26 16:27:04 volumio avahi-daemon[679]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.11. Feb 26 16:27:04 volumio avahi-daemon[679]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 26 16:27:04 volumio dhcpcd[759]: wlan0: deleting default route via 192.168.50.1 Feb 26 16:27:04 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 26 16:27:04 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 26 16:27:04 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 26 16:27:04 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 26 16:27:04 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 26 16:27:04 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 26 16:27:04 volumio welcome[12496]: Resolved ip:[1] 10.174.80.168 Feb 26 16:27:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 26 16:27:04 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 26 16:27:04 volumio systemd[1]: mpd.service: Watchdog timeout (limit 20s)! Feb 26 16:27:04 volumio systemd[1]: mpd.service: Killing process 1671 (mpd) with signal SIGABRT. Feb 26 16:27:04 volumio volumio[1232]: error: Upnp client error: Error: read ECONNRESET Feb 26 16:27:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Feb 26 16:27:04 volumio systemd[1]: mpd.service: Failed with result 'watchdog'. Feb 26 16:27:04 volumio systemd[1]: mpd.service: Consumed 18.084s CPU time. Feb 26 16:27:04 volumio volumio[1232]: error: Upnp client error: Error: read ECONNRESET Feb 26 16:27:04 volumio volumio[1232]: error: MPD error: Error: read ECONNRESET Feb 26 16:27:04 volumio volumio[1232]: error: MPD error: Error: read ECONNRESET Feb 26 16:27:04 volumio volumio[1232]: error: MPD error: Error: read ECONNRESET Feb 26 16:27:04 volumio volumio[1232]: info: Received Get System Info Feb 26 16:27:04 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:27:04 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:27:04 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:27:04 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:04 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:04 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:27:04 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 16:27:04 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 16:27:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 16:27:04 volumio sudo[12502]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 16:27:04 volumio sudo[12502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 16:27:04 volumio sudo[12502]: pam_unix(sudo:session): session closed for user root Feb 26 16:27:05 volumio sudo[7389]: pam_unix(sudo-i:session): session closed for user root Feb 26 16:27:05 volumio ntpd[974]: IO: Deleting interface #4 wlan0, 192.168.50.11#123, interface stats: received=0, sent=0, dropped=0, active_time=232 secs Feb 26 16:27:06 volumio sshd[7266]: Received disconnect from 10.174.80.17 port 44390:11: disconnected by user Feb 26 16:27:06 volumio sshd[7266]: Disconnected from user volumio 10.174.80.17 port 44390 Feb 26 16:27:06 volumio sshd[7170]: pam_unix(sshd:session): session closed for user volumio Feb 26 16:27:06 volumio systemd[1]: session-1.scope: Deactivated successfully. Feb 26 16:27:06 volumio systemd[1]: session-1.scope: Consumed 19.262s CPU time. Feb 26 16:27:06 volumio systemd-logind[686]: Session 1 logged out. Waiting for processes to exit. Feb 26 16:27:06 volumio systemd-logind[686]: Removed session 1. Feb 26 16:27:08 volumio volumio[1232]: info: Volumio Network Manager: Network status updated: 1 Feb 26 16:27:10 volumio mpd[12505]: 2026-02-26T16:27:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 16:27:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 26 16:27:10 volumio volumio[1232]: error: MPD error: The expression evaluated to a falsy value: Feb 26 16:27:10 volumio volumio[1232]: assert.ok(self.idling) Feb 26 16:27:10 volumio volumio[1232]: error: The expression evaluated to a falsy value: Feb 26 16:27:10 volumio volumio[1232]: assert.ok(self.idling) Feb 26 16:27:10 volumio volumio[1232]: error: MPD error: The expression evaluated to a falsy value: Feb 26 16:27:10 volumio volumio[1232]: assert.ok(self.idling) Feb 26 16:27:10 volumio volumio[1232]: error: The expression evaluated to a falsy value: Feb 26 16:27:10 volumio volumio[1232]: assert.ok(self.idling) Feb 26 16:27:10 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:27:13 volumio sshd[12529]: Accepted password for volumio from 10.174.80.17 port 60812 ssh2 Feb 26 16:27:13 volumio sshd[12529]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Feb 26 16:27:13 volumio systemd-logind[686]: New session 3 of user volumio. Feb 26 16:27:13 volumio systemd[1]: Started session-3.scope - Session 3 of User volumio. Feb 26 16:27:13 volumio sshd[12529]: pam_env(sshd:session): deprecated reading of user environment enabled Feb 26 16:27:20 volumio sshd[12562]: Accepted password for volumio from 10.174.80.17 port 46782 ssh2 Feb 26 16:27:20 volumio sshd[12562]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Feb 26 16:27:20 volumio systemd-logind[686]: New session 4 of user volumio. Feb 26 16:27:20 volumio systemd[1]: Started session-4.scope - Session 4 of User volumio. Feb 26 16:27:20 volumio sshd[12562]: pam_env(sshd:session): deprecated reading of user environment enabled Feb 26 16:27:27 volumio sudo[12614]: volumio : TTY=pts/1 ; PWD=/root ; USER=root ; COMMAND=/bin/bash Feb 26 16:27:27 volumio sudo[12614]: pam_unix(sudo-i:session): session opened for user root(uid=0) by volumio(uid=1000) Feb 26 16:27:29 volumio volumio[1232]: info: Received Get System Info Feb 26 16:27:29 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:27:29 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:27:29 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:27:29 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:29 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:29 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:27:29 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 16:27:29 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 16:27:30 volumio kernel: wlan0: authenticate with 0a:55:31:03:72:ae (local address=64:5a:04:02:46:ff) Feb 26 16:27:30 volumio kernel: wlan0: send auth to 0a:55:31:03:72:ae (try 1/3) Feb 26 16:27:30 volumio kernel: wlan0: authenticated Feb 26 16:27:30 volumio kernel: wlan0: associate with 0a:55:31:03:72:ae (try 1/3) Feb 26 16:27:30 volumio kernel: wlan0: RX AssocResp from 0a:55:31:03:72:ae (capab=0x431 status=0 aid=1) Feb 26 16:27:30 volumio kernel: wlan0: associated Feb 26 16:27:30 volumio wpa_supplicant[9593]: Ignore unexpected EVENT_ASSOC in disconnected state Feb 26 16:27:30 volumio dhcpcd[759]: wlan0: carrier acquired Feb 26 16:27:30 volumio dhcpcd[759]: wlan0: IAID 04:02:46:ff Feb 26 16:27:30 volumio dhcpcd[759]: wlan0: rebinding lease of 192.168.50.11 Feb 26 16:27:30 volumio dhcpcd[759]: wlan0: probing address 192.168.50.11/24 Feb 26 16:27:31 volumio volumio[1232]: info: Received Get System Info Feb 26 16:27:31 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:27:31 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:27:31 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:27:31 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:31 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:31 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:27:31 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 16:27:31 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 16:27:31 volumio dhcpcd[759]: wlan0: soliciting an IPv6 router Feb 26 16:27:34 volumio sudo[12663]: volumio : TTY=pts/0 ; PWD=/root ; USER=root ; COMMAND=/bin/bash Feb 26 16:27:34 volumio sudo[12663]: pam_unix(sudo-i:session): session opened for user root(uid=0) by volumio(uid=1000) Feb 26 16:27:35 volumio dhcpcd[759]: wlan0: leased 192.168.50.11 for 3600 seconds Feb 26 16:27:35 volumio avahi-daemon[679]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.11. Feb 26 16:27:35 volumio avahi-daemon[679]: New relevant interface wlan0.IPv4 for mDNS. Feb 26 16:27:35 volumio avahi-daemon[679]: Registering new address record for 192.168.50.11 on wlan0.IPv4. Feb 26 16:27:35 volumio dhcpcd[759]: wlan0: adding route to 192.168.50.0/24 Feb 26 16:27:35 volumio dhcpcd[759]: wlan0: adding default route via 192.168.50.1 Feb 26 16:27:35 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 26 16:27:35 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 26 16:27:35 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 26 16:27:35 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 26 16:27:35 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 26 16:27:35 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 26 16:27:35 volumio welcome[12678]: Resolved ip:[2] 10.174.80.168 192.168.50.11 Feb 26 16:27:35 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 26 16:27:35 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 26 16:27:36 volumio volumio[1232]: info: Received Get System Info Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:36 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: adding e42dfdf2-dddc-4846-8d8e-a5bd5cd301d4 Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: Found device Volumio Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:36 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: this is already registered, e42dfdf2-dddc-4846-8d8e-a5bd5cd301d4 Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: Found device Volumio Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:36 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: this is already registered, e42dfdf2-dddc-4846-8d8e-a5bd5cd301d4 Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: Found device Volumio Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:36 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: this is already registered, e42dfdf2-dddc-4846-8d8e-a5bd5cd301d4 Feb 26 16:27:36 volumio volumio[1232]: info: Discovery: Found device Volumio Feb 26 16:27:36 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:36 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:36 volumio ntpd[974]: IO: Listen normally on 5 wlan0 192.168.50.11:123 Feb 26 16:27:36 volumio ntpd[974]: IO: new interface(s) found: waking up resolver Feb 26 16:27:38 volumio volumio[1232]: info: Volumio Network Manager: Network status updated: 3 Feb 26 16:27:46 volumio dhcpcd[759]: eth0: pid 12761 deleted default route via 10.174.80.1 Feb 26 16:27:47 volumio volumio[1232]: info: Received Get System Info Feb 26 16:27:47 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:27:47 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:27:47 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:27:47 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:27:47 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:47 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:27:47 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 16:27:47 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 16:27:55 volumio volumio[1232]: info: Preload queue cleared Feb 26 16:27:55 volumio volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 16:27:55 volumio volumio[1232]: info: CoreStateMachine::ClearQueue Feb 26 16:27:55 volumio volumio[1232]: info: CoreStateMachine::stop Feb 26 16:27:55 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:27:55 volumio volumio[1232]: info: CorePlayQueue::clearPlayQueue Feb 26 16:27:55 volumio volumio[1232]: info: CorePlayQueue::saveQueue Feb 26 16:27:55 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Feb 26 16:27:55 volumio volumio[1232]: info: CoreStateMachine::addQueueItems Feb 26 16:27:55 volumio volumio[1232]: info: CorePlayQueue::addQueueItems Feb 26 16:27:55 volumio volumio[1232]: info: Preload queue cleared Feb 26 16:27:55 volumio volumio[1232]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s298310 Feb 26 16:27:55 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 26 16:27:56 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Feb 26 16:27:56 volumio volumio[1232]: info: CorePlayQueue::saveQueue Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::updateTrackBlock Feb 26 16:27:56 volumio volumio[1232]: info: CorePlayQueue::getTrackBlock Feb 26 16:27:56 volumio volumio[1232]: info: CoreCommandRouter::volumioPlay Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::play index 0 Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::stop Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::play index undefined Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:27:56 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::startPlaybackTimer Feb 26 16:27:56 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:56 volumio volumio[1232]: info: [1772098076619] ControllerWebradio::clearAddPlayTrack Feb 26 16:27:56 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 16:27:56 volumio volumio[1232]: info: sendMpdCommand stop took 3 milliseconds Feb 26 16:27:56 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 16:27:56 volumio volumio[1232]: info: Feb 26 16:27:56 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:56 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:56 volumio volumio[1232]: info: sendMpdCommand clear took 2 milliseconds Feb 26 16:27:56 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand load "http://94.130.113.214:8000/dubtechno" Feb 26 16:27:56 volumio volumio[1232]: info: Feb 26 16:27:56 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:56 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:56 volumio volumio[1232]: info: Feb 26 16:27:56 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:56 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:56 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:27:56 volumio volumio[1232]: info: ------------------------------ 2ms Feb 26 16:27:56 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:27:56 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:27:56 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand add "http://94.130.113.214:8000/dubtechno" Feb 26 16:27:56 volumio volumio[1232]: info: ------------------------------ 285ms Feb 26 16:27:56 volumio volumio[1232]: info: ------------------------------ 284ms Feb 26 16:27:56 volumio volumio[1232]: info: Feb 26 16:27:56 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:56 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:56 volumio volumio[1232]: info: sendMpdCommand add "http://94.130.113.214:8000/dubtechno" took 16 milliseconds Feb 26 16:27:56 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 26 16:27:56 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand play Feb 26 16:27:56 volumio volumio[1232]: info: Feb 26 16:27:56 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:56 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:56 volumio volumio[1232]: info: Feb 26 16:27:56 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:56 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:56 volumio volumio[1232]: info: ------------------------------ 43ms Feb 26 16:27:56 volumio volumio[1232]: info: sendMpdCommand play took 29 milliseconds Feb 26 16:27:56 volumio volumio[1232]: info: ------------------------------ 21ms Feb 26 16:27:56 volumio volumio[1232]: info: ------------------------------ 11ms Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand status took 14 milliseconds Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand status took 8 milliseconds Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand status took 6 milliseconds Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: 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":"dubtechno","artist":null,"album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:27:57 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 29ms Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: 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":"dubtechno","artist":null,"album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:27:57 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Feb 26 16:27:57 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: 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":"dubtechno","artist":null,"album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:27:57 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Feb 26 16:27:57 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 48ms Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 70ms Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:57 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:57 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:27:57 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:27:57 volumio volumio[1232]: info: Feb 26 16:27:57 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 6ms Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand status took 6 milliseconds Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 5ms Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand status took 4 milliseconds Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 3ms Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand status took 2 milliseconds Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 16:27:57 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:27:57 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":684,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"??S - ??V","artist":"Radio Schizoid","album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:27:57 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Feb 26 16:27:57 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":684,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"??S - ??V","artist":"Radio Schizoid","album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:27:57 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Feb 26 16:27:57 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:27:57 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:27:57 volumio volumio[1232]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"??S - ??V","artist":"Radio Schizoid","album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:27:57 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState stateService play Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus play Feb 26 16:27:57 volumio volumio[1232]: info: Received an update from plugin. extracting info from payload Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:27:57 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 44ms Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 46ms Feb 26 16:27:57 volumio volumio[1232]: info: ------------------------------ 44ms Feb 26 16:28:01 volumio volumio[1232]: info: Preload queue cleared Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::ClearQueue Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::stop Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::stPlaybackTimer Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::updateTrackBlock Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrackBlock Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::serviceStop Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::serviceStop Feb 26 16:28:01 volumio volumio[1232]: info: [1772098081670] ControllerWebradio::stop Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::clearPlayQueue Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::saveQueue Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::addQueueItems Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::addQueueItems Feb 26 16:28:01 volumio volumio[1232]: info: Preload queue cleared Feb 26 16:28:01 volumio volumio[1232]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s130595 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 26 16:28:01 volumio volumio[1232]: info: Feb 26 16:28:01 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:01 volumio volumio[1232]: info: sendMpdCommand stop took 30 milliseconds Feb 26 16:28:01 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:01 volumio volumio[1232]: info: Feb 26 16:28:01 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:01 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:01 volumio volumio[1232]: info: Feb 26 16:28:01 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:01 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:01 volumio volumio[1232]: info: sendMpdCommand status took 3 milliseconds Feb 26 16:28:01 volumio volumio[1232]: info: sendMpdCommand status took 2 milliseconds Feb 26 16:28:01 volumio volumio[1232]: info: sendMpdCommand status took 1 milliseconds Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:01 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 16:28:01 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 16:28:01 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:01 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:01 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??S - ??V","artist":"Radio Schizoid","album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:28:01 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: No code Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??S - ??V","artist":"Radio Schizoid","album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:28:01 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: No code Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??S - ??V","artist":"Radio Schizoid","album":null,"uri":"http://94.130.113.214:8000/dubtechno","trackType":""} Feb 26 16:28:01 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: No code Feb 26 16:28:01 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:01 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:01 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:01 volumio volumio[1232]: info: ------------------------------ 38ms Feb 26 16:28:01 volumio volumio[1232]: info: ------------------------------ 37ms Feb 26 16:28:01 volumio volumio[1232]: info: ------------------------------ 36ms Feb 26 16:28:02 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Feb 26 16:28:02 volumio volumio[1232]: info: CorePlayQueue::saveQueue Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::updateTrackBlock Feb 26 16:28:02 volumio volumio[1232]: info: CorePlayQueue::getTrackBlock Feb 26 16:28:02 volumio volumio[1232]: info: CoreCommandRouter::volumioPlay Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::play index 0 Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::stop Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::play index undefined Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:02 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:02 volumio volumio[1232]: info: CoreStateMachine::startPlaybackTimer Feb 26 16:28:02 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:02 volumio volumio[1232]: info: [1772098082293] ControllerWebradio::clearAddPlayTrack Feb 26 16:28:02 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 16:28:02 volumio volumio[1232]: info: sendMpdCommand stop took 2 milliseconds Feb 26 16:28:02 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 16:28:02 volumio volumio[1232]: info: Feb 26 16:28:02 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:02 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:02 volumio volumio[1232]: info: sendMpdCommand clear took 2 milliseconds Feb 26 16:28:02 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand load "https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771" Feb 26 16:28:02 volumio volumio[1232]: info: Feb 26 16:28:02 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:02 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:02 volumio volumio[1232]: info: Feb 26 16:28:02 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:02 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:02 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:28:02 volumio volumio[1232]: info: ------------------------------ 4ms Feb 26 16:28:04 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:28:04 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:28:04 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand add "https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771" Feb 26 16:28:04 volumio volumio[1232]: info: ------------------------------ 1887ms Feb 26 16:28:04 volumio volumio[1232]: info: ------------------------------ 1889ms Feb 26 16:28:04 volumio volumio[1232]: info: Feb 26 16:28:04 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:04 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:04 volumio volumio[1232]: info: sendMpdCommand add "https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771" took 9 milliseconds Feb 26 16:28:04 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 26 16:28:04 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand play Feb 26 16:28:04 volumio volumio[1232]: info: Feb 26 16:28:04 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:04 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:04 volumio volumio[1232]: info: Feb 26 16:28:04 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:04 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:04 volumio volumio[1232]: info: ------------------------------ 7ms Feb 26 16:28:04 volumio volumio[1232]: info: sendMpdCommand play took 7 milliseconds Feb 26 16:28:04 volumio volumio[1232]: info: ------------------------------ 2ms Feb 26 16:28:04 volumio volumio[1232]: info: ------------------------------ 2ms Feb 26 16:28:05 volumio volumio[1232]: info: Feb 26 16:28:05 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:05 volumio volumio[1232]: info: Feb 26 16:28:05 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:05 volumio volumio[1232]: info: Feb 26 16:28:05 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:05 volumio volumio[1232]: error: MPD returned error for command status: Failed to decode "https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771"; got HTTP status 404 Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand status took 6 milliseconds Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:05 volumio volumio[1232]: info: Feb 26 16:28:05 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:05 volumio volumio[1232]: error: MPD returned error for command status: Failed to decode "https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771"; got HTTP status 404 Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand status took 25 milliseconds Feb 26 16:28:05 volumio volumio[1232]: error: MPD returned error for command status: Failed to decode "https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771"; got HTTP status 404 Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand status took 30 milliseconds Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:05 volumio volumio[1232]: info: Feb 26 16:28:05 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:05 volumio volumio[1232]: info: Feb 26 16:28:05 volumio volumio[1232]: ---------------------------- MPD announces state update: player Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::getState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand status Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand clearerror took 40 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 40 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand status took 39 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand clearerror took 21 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand clearerror took 15 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand status took 5 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand status took 4 milliseconds Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseState Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:05 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8846a94e-9874-4692-a1a0-ec7aadbe2771","artist":null,"album":null,"uri":"https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771","trackType":""} Feb 26 16:28:05 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: No code Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:05 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8846a94e-9874-4692-a1a0-ec7aadbe2771","artist":null,"album":null,"uri":"https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771","trackType":""} Feb 26 16:28:05 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: No code Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:05 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8846a94e-9874-4692-a1a0-ec7aadbe2771","artist":null,"album":null,"uri":"https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771","trackType":""} Feb 26 16:28:05 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: No code Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: ------------------------------ 134ms Feb 26 16:28:05 volumio volumio[1232]: info: ------------------------------ 142ms Feb 26 16:28:05 volumio volumio[1232]: info: ------------------------------ 142ms Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 97 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 94 milliseconds Feb 26 16:28:05 volumio volumio[1232]: info: sendMpdCommand playlistinfo took 94 milliseconds Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:05 volumio volumio[1232]: verbose: ControllerMpd::parseTrackInfo Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:05 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8846a94e-9874-4692-a1a0-ec7aadbe2771","artist":null,"album":null,"uri":"https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771","trackType":""} Feb 26 16:28:05 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: No code Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:05 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8846a94e-9874-4692-a1a0-ec7aadbe2771","artist":null,"album":null,"uri":"https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771","trackType":""} Feb 26 16:28:05 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: No code Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: ControllerMpd::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::servicePushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:05 volumio volumio[1232]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8846a94e-9874-4692-a1a0-ec7aadbe2771","artist":null,"album":null,"uri":"https://streams.radiomast.io/8846a94e-9874-4692-a1a0-ec7aadbe2771","trackType":""} Feb 26 16:28:05 volumio volumio[1232]: verbose: CURRENT POSITION 0 Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState stateService stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: No code Feb 26 16:28:05 volumio volumio[1232]: info: CoreStateMachine::pushState Feb 26 16:28:05 volumio volumio[1232]: info: CoreCommandRouter::volumioPushState Feb 26 16:28:05 volumio volumio[1232]: info: ------------------------------ 214ms Feb 26 16:28:05 volumio volumio[1232]: info: ------------------------------ 207ms Feb 26 16:28:05 volumio volumio[1232]: info: ------------------------------ 210ms Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:28:08 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:28:08 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.50.11:3000 from 192.168.50.13 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 16:28:08 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.50.11:3000 from 192.168.50.13 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:28:08 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:28:08 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:28:09 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:09.320+07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.50.13:51774 Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.425+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.50.13:51774 @ 0xc000340780" latency=810.804693ms timeout=10s Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.426+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.50.13:51774 @ 0xc000340780" latency=812.438424ms platform=PLATFORM_IOS version=5.260120.0 Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.433+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" Feb 26 16:28:12 volumio volumio[1232]: info: Received Get System Info Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:28:12 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.436+07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" name=Volumio Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.440+07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" language=ru Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.443+07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" timezone=Asia/Novosibirsk Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.444+07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" available=true connected=true macAddress=70:54:d2:9b:2b:6a ip4Address=10.174.80.168/23 ip6Address= Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.446+07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" available=true connected=true macAddress=64:5a:04:02:46:ff ip4Address=192.168.50.11/24 ip6Address= ssid=mks Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.447+07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" setupComplete=true Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 16:28:12 volumio volumio[1232]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Feb 26 16:28:12 volumio volumio[1232]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 26 16:28:12 volumio volumio[1232]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 26 16:28:12 volumio volumio[1232]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Feb 26 16:28:12 volumio volumio[1232]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 26 16:28:12 volumio volumio[1232]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at ChildProcess._handle.onexit (node:internal/child_process:305:5)"} Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.530+07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" selectedOutputId=0,0 Feb 26 16:28:12 volumio volumio[1232]: info: Received Get System Info Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:28:12 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.583+07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" currentVersion=4.096 latestVersion=4.096 Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.583+07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.50.13:51774 @ 0xc000340780" status=UPDATE_STATUS_NONE progress=0 Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.591+07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" userId= Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.591+07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" providers=3 Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 16:28:12 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.593+07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" plugins=0 Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.596+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" state=STATUS_PLAYING positionMs=10070 volume=50 Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.596+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.13:51774 @ 0xc000340780" id=http://94.130.113.214:8000/dubtechno title="??S - ??V" Feb 26 16:28:12 volumio volumio5-onboarding[1607]: time=2026-02-26T16:28:12.661+07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.50.13:51774 @ 0xc000340780" latency=112.90322ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 26 16:28:17 volumio sudo[12914]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 16:28:17 volumio sudo[12914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 16:28:17 volumio sudo[12914]: pam_unix(sudo:session): session closed for user root Feb 26 16:28:17 volumio sudo[12916]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 16:28:17 volumio sudo[12916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 16:28:17 volumio sudo[12916]: pam_unix(sudo:session): session closed for user root Feb 26 16:28:17 volumio sudo[12925]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 16:28:17 volumio sudo[12925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 16:28:17 volumio sudo[12925]: pam_unix(sudo:session): session closed for user root Feb 26 16:28:17 volumio sudo[12928]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 16:28:17 volumio sudo[12928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 16:28:17 volumio sudo[12928]: pam_unix(sudo:session): session closed for user root Feb 26 16:28:18 volumio volumio[1232]: verbose: New Socket.io Connection to 192.168.50.11 from 192.168.50.13 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Feb 26 16:28:18 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:28:18 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 16:28:18 volumio volumio[1232]: info: Listing playlists Feb 26 16:28:19 volumio volumio[1232]: info: Preload queue cleared Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 16:28:19 volumio volumio[1232]: info: CoreStateMachine::ClearQueue Feb 26 16:28:19 volumio volumio[1232]: info: CoreStateMachine::stop Feb 26 16:28:19 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:19 volumio volumio[1232]: info: CorePlayQueue::clearPlayQueue Feb 26 16:28:19 volumio volumio[1232]: info: CorePlayQueue::saveQueue Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Feb 26 16:28:19 volumio volumio[1232]: info: CoreStateMachine::addQueueItems Feb 26 16:28:19 volumio volumio[1232]: info: CorePlayQueue::addQueueItems Feb 26 16:28:19 volumio volumio[1232]: info: Preload queue cleared Feb 26 16:28:19 volumio volumio[1232]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s311837 Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 16:28:19 volumio volumio[1232]: info: Received Get System Info Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 16:28:19 volumio volumio[1232]: info: Discovery: Getting this device information Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::volumioGetState Feb 26 16:28:19 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:19 volumio volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 16:28:20 volumio volumio[1232]: info: CoreCommandRouter::volumioPushQueue Feb 26 16:28:20 volumio volumio[1232]: info: CorePlayQueue::saveQueue Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::updateTrackBlock Feb 26 16:28:20 volumio volumio[1232]: info: CorePlayQueue::getTrackBlock Feb 26 16:28:20 volumio volumio[1232]: info: CoreCommandRouter::volumioPlay Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::play index 0 Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::stop Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::play index undefined Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 16:28:20 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:20 volumio volumio[1232]: info: CoreStateMachine::startPlaybackTimer Feb 26 16:28:20 volumio volumio[1232]: info: CorePlayQueue::getTrack 0 Feb 26 16:28:20 volumio volumio[1232]: info: [1772098100343] ControllerWebradio::clearAddPlayTrack Feb 26 16:28:20 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 16:28:20 volumio volumio[1232]: info: sendMpdCommand stop took 1 milliseconds Feb 26 16:28:20 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 16:28:20 volumio volumio[1232]: info: Feb 26 16:28:20 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:20 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:20 volumio volumio[1232]: info: sendMpdCommand clear took 3 milliseconds Feb 26 16:28:20 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand load "https://techno.stream.laut.fm/techno?ref=radiodns" Feb 26 16:28:20 volumio volumio[1232]: info: Feb 26 16:28:20 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:20 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:20 volumio volumio[1232]: info: Feb 26 16:28:20 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:20 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:21 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:28:21 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:28:21 volumio volumio[1232]: error: updateQueue error: null Feb 26 16:28:21 volumio volumio[1232]: info: ------------------------------ 909ms Feb 26 16:28:21 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand add "https://techno.stream.laut.fm/techno?ref=radiodns" Feb 26 16:28:21 volumio volumio[1232]: info: ------------------------------ 908ms Feb 26 16:28:21 volumio volumio[1232]: info: ------------------------------ 908ms Feb 26 16:28:21 volumio volumio[1232]: info: Feb 26 16:28:21 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:21 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:21 volumio volumio[1232]: info: sendMpdCommand add "https://techno.stream.laut.fm/techno?ref=radiodns" took 2 milliseconds Feb 26 16:28:21 volumio volumio[1232]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 26 16:28:21 volumio volumio[1232]: verbose: ControllerMpd::sendMpdCommand play Feb 26 16:28:21 volumio volumio[1232]: info: Feb 26 16:28:21 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:21 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:21 volumio volumio[1232]: info: Feb 26 16:28:21 volumio volumio[1232]: ---------------------------- MPD announces system playlist update Feb 26 16:28:21 volumio volumio[1232]: info: Ignoring MPD Status Update Feb 26 16:28:21 volumio volumio[1232]: info: ------------------------------ 7ms Feb 26 16:28:21 volumio volumio[1232]: info: sendMpdCommand play took 6 milliseconds Feb 26 16:28:21 volumio volumio[1232]: info: ------------------------------ 3ms Feb 26 16:28:21 volumio volumio[1232]: info: ------------------------------ 2ms Feb 26 16:28:21 volumio volumio[1232]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 16:28:21 volumio volumio[1232]: Error: read ECONNRESET Feb 26 16:28:21 volumio volumio[1232]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:217:20) { Feb 26 16:28:21 volumio volumio[1232]: errno: -104, Feb 26 16:28:21 volumio volumio[1232]: code: 'ECONNRESET', Feb 26 16:28:21 volumio volumio[1232]: syscall: 'read' Feb 26 16:28:21 volumio volumio[1232]: } Feb 26 16:28:21 volumio volumio[1232]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 16:28:22 volumio sudo[12969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 16:27' Feb 26 16:28:22 volumio sudo[12969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"