Dec 30 14:34:05 volumio wpa_supplicant[6511]: wlan0: CTRL-EVENT-DISCONNECTED bssid=50:ff:20:b4:15:47 reason=3 locally_generated=1 Dec 30 14:34:05 volumio wpa_supplicant[6511]: BSSID 50:ff:20:b4:15:47 ignore list count incremented to 2, ignoring for 10 seconds Dec 30 14:34:05 volumio dhcpcd[963]: wlan0: carrier lost Dec 30 14:34:05 volumio avahi-daemon[6650]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 30 14:34:05 volumio avahi-daemon[6650]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.73. Dec 30 14:34:05 volumio volumio[6702]: error: Volumio Network Manager: Error from the watcher script: wlan0: error fetching interface information: Device not found Dec 30 14:34:05 volumio avahi-daemon[6650]: Withdrawing address record for 192.168.1.73 on wlan0. Dec 30 14:34:05 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:34:05 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 30 14:34:05 volumio dhcpcd[963]: wlan0: deleting route to 192.168.1.0/24 Dec 30 14:34:05 volumio dhcpcd[963]: wlan0: deleting default route via 192.168.1.1 Dec 30 14:34:05 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Dec 30 14:34:05 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 30 14:34:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 30 14:34:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 30 14:34:05 volumio volumio[6702]: info: Discovery: A device disappeared from network Dec 30 14:34:05 volumio volumio[6702]: info: Discovery: Device volumio disappeared from network Dec 30 14:34:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 30 14:34:05 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Dec 30 14:34:05 volumio systemd[1]: Stopping ifup@wlan0.service - ifup for wlan0... Dec 30 14:34:05 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:05 volumio welcome[7473]: Resolved ip:[0] Dec 30 14:34:05 volumio dhcpcd[963]: wlan0: removing interface Dec 30 14:34:05 volumio volumio[6702]: info: Volumio Network Manager: Network status updated: 0 Dec 30 14:34:05 volumio volumio[6702]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 30 14:34:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 30 14:34:05 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:34:05 volumio volumio[6702]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 30 14:34:05 volumio volumio[6702]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 30 14:34:06 volumio systemd[1]: ifup@wlan0.service: Deactivated successfully. Dec 30 14:34:06 volumio systemd[1]: Stopped ifup@wlan0.service - ifup for wlan0. Dec 30 14:34:06 volumio kernel: usb 1-5.4.1: new high-speed USB device number 9 using xhci_hcd Dec 30 14:34:06 volumio kernel: usb 1-5.4.1: New USB device found, idVendor=0bda, idProduct=b812, bcdDevice= 2.10 Dec 30 14:34:06 volumio kernel: usb 1-5.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 30 14:34:06 volumio kernel: usb 1-5.4.1: Product: 802.11ac NIC Dec 30 14:34:06 volumio kernel: usb 1-5.4.1: Manufacturer: Realtek Dec 30 14:34:06 volumio kernel: usb 1-5.4.1: SerialNumber: 123456 Dec 30 14:34:06 volumio kernel: rtw_8822bu 1-5.4.1:1.0: Firmware version 30.20.0, H2C version 14 Dec 30 14:34:06 volumio volumio[6702]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 14:34:06 volumio volumio[6702]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Dec 30 14:34:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 14:34:06 volumio kernel: rtw_8822bu 1-5.4.1:1.0: write register 0xc4 failed with -71 Dec 30 14:34:06 volumio kernel: rtw_8822bu 1-5.4.1:1.0: rtw_usb_reg_sec: reg 0x4e0, usb write 1 fail, status: -71 Dec 30 14:34:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 14:34:06 volumio sudo[7526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 14:34:06 volumio sudo[7526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio kernel: usb 1-5.4.1: USB disconnect, device number 9 Dec 30 14:34:06 volumio sudo[7531]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 14:34:06 volumio sudo[7526]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio sudo[7531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio sudo[7539]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 14:34:06 volumio sudo[7539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio sudo[7542]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 14:34:06 volumio sudo[7542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio sudo[7531]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 14:34:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 14:34:06 volumio sudo[7552]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 14:34:06 volumio sudo[7552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio sudo[7552]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio sudo[7539]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio sudo[7560]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 14:34:06 volumio sudo[7560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio sudo[7542]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio sudo[7560]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio sudo[7568]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 14:34:06 volumio sudo[7568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio sudo[7568]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio volumio[6702]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 30 14:34:06 volumio volumio[6702]: wlan0 Interface doesn't support scanning. Dec 30 14:34:06 volumio volumio[6702]: info: Cannot use regular scanning, forcing with ap-force Dec 30 14:34:06 volumio sudo[7578]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 30 14:34:06 volumio sudo[7578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:06 volumio sudo[7578]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:06 volumio volumio[6702]: command failed: No such device (-19) Dec 30 14:34:06 volumio volumio[6702]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 30 14:34:06 volumio volumio[6702]: command failed: No such device (-19) Dec 30 14:34:07 volumio kernel: usb 1-5.4.1: new high-speed USB device number 10 using xhci_hcd Dec 30 14:34:07 volumio kernel: usb 1-5.4.1: New USB device found, idVendor=0bda, idProduct=b812, bcdDevice= 2.10 Dec 30 14:34:07 volumio kernel: usb 1-5.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 30 14:34:07 volumio kernel: usb 1-5.4.1: Product: 802.11ac NIC Dec 30 14:34:07 volumio kernel: usb 1-5.4.1: Manufacturer: Realtek Dec 30 14:34:07 volumio kernel: usb 1-5.4.1: SerialNumber: 123456 Dec 30 14:34:07 volumio kernel: rtw_8822bu 1-5.4.1:1.0: Firmware version 30.20.0, H2C version 14 Dec 30 14:34:07 volumio ntpd[1032]: IO: Deleting interface #8 wlan0, 192.168.1.73#123, interface stats: received=11, sent=44, dropped=0, active_time=160 secs Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 195.46.37.22 unlink local addr 192.168.1.73 -> Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 85.115.212.254 unlink local addr 192.168.1.73 -> Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 54.37.233.160 unlink local addr 192.168.1.73 -> Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 162.159.200.1 unlink local addr 192.168.1.73 -> Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 194.146.251.102 unlink local addr 192.168.1.73 -> Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 194.146.251.100 unlink local addr 192.168.1.73 -> Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 91.212.242.19 unlink local addr 192.168.1.73 -> Dec 30 14:34:07 volumio ntpd[1032]: PROTO: 94.154.96.7 unlink local addr 192.168.1.73 -> Dec 30 14:34:08 volumio (udev-worker)[7447]: Network interface NamePolicy= disabled on kernel command line. Dec 30 14:34:08 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Dec 30 14:34:08 volumio rfkill[7594]: unblock set for type wifi Dec 30 14:34:08 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - RTL88x2bu [AC1200 Techkey]. Dec 30 14:34:08 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Dec 30 14:34:08 volumio dhcpcd[963]: wlan0: waiting for carrier Dec 30 14:34:09 volumio wpa_supplicant[6511]: wlan0: SME: Trying to authenticate with 50:ff:20:b4:15:45 (SSID='Fly' freq=2452 MHz) Dec 30 14:34:09 volumio sh[7648]: wlan0=wlan0 Dec 30 14:34:09 volumio volumio[6702]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 30 14:34:09 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:34:09 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:09 volumio kernel: wlan0: authenticate with 50:ff:20:b4:15:45 (local address=a0:a3:f0:aa:2c:20) Dec 30 14:34:09 volumio wpa_supplicant[6511]: wlan0: Trying to associate with 50:ff:20:b4:15:45 (SSID='Fly' freq=2452 MHz) Dec 30 14:34:09 volumio kernel: wlan0: send auth to 50:ff:20:b4:15:45 (try 1/3) Dec 30 14:34:09 volumio kernel: wlan0: authenticated Dec 30 14:34:09 volumio kernel: wlan0: associate with 50:ff:20:b4:15:45 (try 1/3) Dec 30 14:34:09 volumio kernel: wlan0: RX AssocResp from 50:ff:20:b4:15:45 (capab=0x1411 status=0 aid=1) Dec 30 14:34:09 volumio wpa_supplicant[6511]: wlan0: Associated with 50:ff:20:b4:15:45 Dec 30 14:34:09 volumio kernel: wlan0: associated Dec 30 14:34:09 volumio wpa_supplicant[6511]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 30 14:34:09 volumio wpa_supplicant[6511]: wlan0: WPA: Key negotiation completed with 50:ff:20:b4:15:45 [PTK=CCMP GTK=CCMP] Dec 30 14:34:09 volumio wpa_supplicant[6511]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:ff:20:b4:15:45 completed [id=0 id_str=] Dec 30 14:34:09 volumio dhcpcd[963]: wlan0: carrier acquired Dec 30 14:34:09 volumio dhcpcd[963]: wlan0: connected to Access Point: Fly Dec 30 14:34:09 volumio dhcpcd[963]: wlan0: IAID f0:aa:2c:20 Dec 30 14:34:09 volumio dhcpcd[963]: wlan0: adding address fe80::fd5a:dc8c:25fc:680 Dec 30 14:34:09 volumio dhcpcd[963]: ipv6_addaddr1: Permission denied Dec 30 14:34:09 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 50:ff:20:b4:15:45 Dec 30 14:34:10 volumio dhcpcd[963]: wlan0: soliciting an IPv6 router Dec 30 14:34:10 volumio dhcpcd[963]: wlan0: rebinding lease of 192.168.1.73 Dec 30 14:34:10 volumio dhcpcd[963]: wlan0: probing address 192.168.1.73/24 Dec 30 14:34:12 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 30 14:34:15 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri Dec 30 14:34:15 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:16 volumio dhcpcd[963]: wlan0: leased 192.168.1.73 for 25200 seconds Dec 30 14:34:16 volumio avahi-daemon[6650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.73. Dec 30 14:34:16 volumio avahi-daemon[6650]: New relevant interface wlan0.IPv4 for mDNS. Dec 30 14:34:16 volumio avahi-daemon[6650]: Registering new address record for 192.168.1.73 on wlan0.IPv4. Dec 30 14:34:16 volumio dhcpcd[963]: wlan0: adding route to 192.168.1.0/24 Dec 30 14:34:16 volumio dhcpcd[963]: wlan0: adding default route via 192.168.1.1 Dec 30 14:34:16 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:34:16 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 30 14:34:16 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 30 14:34:16 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 30 14:34:16 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 30 14:34:16 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 30 14:34:16 volumio welcome[7685]: Resolved ip:[1] 192.168.1.73 Dec 30 14:34:16 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 30 14:34:16 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:34:17 volumio ntpd[1032]: IO: Listen normally on 9 wlan0 192.168.1.73:123 Dec 30 14:34:17 volumio ntpd[1032]: IO: new interface(s) found: waking up resolver Dec 30 14:34:17 volumio ntpd[1032]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 30 14:34:17 volumio ntpd[1032]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 30 14:34:17 volumio ntpd[1032]: DNS: Pool taking: 91.212.242.21 Dec 30 14:34:17 volumio ntpd[1032]: DNS: Pool skipping: 195.46.37.22 Dec 30 14:34:17 volumio ntpd[1032]: DNS: Pool taking: 46.171.0.154 Dec 30 14:34:17 volumio ntpd[1032]: DNS: Pool taking: 89.78.89.128 Dec 30 14:34:17 volumio ntpd[1032]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 30 14:34:18 volumio ntpd[1032]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 30 14:34:18 volumio ntpd[1032]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool taking: 51.68.141.5 Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool taking: 178.215.228.24 Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool taking: 46.174.178.150 Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool taking: 162.159.200.123 Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool skipping: 2a05:f480:2400:18af:5400:4ff:fe0c:1486 Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool skipping: 2001:678:8::123 Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool taking: 2a05:9404::15e Dec 30 14:34:18 volumio ntpd[1032]: DNS: Pool skipping: 2606:4700:f1::1 Dec 30 14:34:18 volumio ntpd[1032]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 30 14:34:19 volumio volumio[6702]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 14:34:19 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 14:34:19 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 14:34:19 volumio sudo[7721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 14:34:19 volumio sudo[7721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:19 volumio sudo[7721]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:19 volumio sudo[7726]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 14:34:19 volumio sudo[7726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:20 volumio sudo[7732]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 14:34:20 volumio sudo[7726]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:20 volumio sudo[7732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:20 volumio sudo[7732]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:20 volumio sudo[7742]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 14:34:20 volumio sudo[7742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:20 volumio sudo[7742]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:20 volumio sudo[7737]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 14:34:20 volumio sudo[7737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:20 volumio sudo[7737]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:20 volumio sudo[7747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 14:34:20 volumio sudo[7747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:20 volumio sudo[7747]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:20 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 14:34:20 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 14:34:20 volumio sudo[7753]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 14:34:20 volumio sudo[7753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:21 volumio volumio[6702]: info: Discovery: adding fc7797e2-a756-4f8a-9d51-84e0618d2ce1 Dec 30 14:34:21 volumio volumio[6702]: info: Discovery: Found device Volumio Dec 30 14:34:21 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:34:21 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:22 volumio ntpd[1032]: CLOCK: time stepped by -0.646958 Dec 30 14:34:22 volumio ntpd[1032]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 30 14:34:23 volumio volumio[6702]: info: Adding plugin bluetooth to MyMusic Plugins Dec 30 14:34:23 volumio volumio[6702]: info: Adding plugin multiroom to MyMusic Plugins Dec 30 14:34:23 volumio volumio[6702]: info: Adding plugin metavolumio to MyMusic Plugins Dec 30 14:34:23 volumio volumio[6702]: info: Adding plugin cd_controller to MyMusic Plugins Dec 30 14:34:23 volumio volumio[6702]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 30 14:34:23 volumio volumio[6702]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 30 14:34:23 volumio volumio[6702]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 30 14:34:23 volumio volumio[6702]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 30 14:34:24 volumio volumio[6702]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 30 14:34:24 volumio volumio[6702]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 30 14:34:24 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:24 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:24 volumio volumio[6702]: info: Starting MyVolumio Remote Streaming Endpoints Dec 30 14:34:24 volumio volumio[6702]: info: MyVolumio login type: Token Dec 30 14:34:24 volumio volumio[6702]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 30 14:34:24 volumio volumio[6702]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 30 14:34:25 volumio volumio[6702]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 30 14:34:25 volumio volumio[6702]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 30 14:34:25 volumio volumio[6702]: info: Streaming services startup Dec 30 14:34:25 volumio volumio[6702]: info: Starting Streaming Daemon Dec 30 14:34:25 volumio sudo[7778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 14:34:25 volumio sudo[7778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:25 volumio volumio[6702]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 30 14:34:25 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 30 14:34:25 volumio sudo[7778]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:25 volumio volumio[6702]: info: Volumio Network Manager: Network status updated: 2 Dec 30 14:34:25 volumio volumio[6702]: error: Cannot start Volumio Streaming Daemon Dec 30 14:34:25 volumio volumio[6702]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 14:34:25 volumio volumio[6702]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 14:34:27 volumio volumio[6702]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 30 14:34:27 volumio sudo[7753]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:27 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri Dec 30 14:34:28 volumio volumio[6702]: info: MyVolumio token set successfully Dec 30 14:34:28 volumio volumio[6702]: info: MYVOLUMIO: Adding device Dec 30 14:34:28 volumio volumio[6702]: info: MYVOLUMIO: Evaluating Server Dec 30 14:34:28 volumio volumio[6702]: info: MyVolumio status changed Dec 30 14:34:28 volumio volumio[6702]: info: Streaming services startup Dec 30 14:34:28 volumio volumio[6702]: info: Starting Streaming Daemon Dec 30 14:34:28 volumio volumio[6702]: info: Removing browser output: myVolumio user plan is not superstar Dec 30 14:34:28 volumio volumio[6702]: info: Removing audio output: Dec 30 14:34:28 volumio volumio[6702]: info: Stoppping Tunnel 1 Dec 30 14:34:28 volumio sudo[7823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 30 14:34:28 volumio sudo[7823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:28 volumio sudo[7825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 30 14:34:28 volumio sudo[7825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:28 volumio sudo[7823]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:28 volumio volumio[6702]: error: Cannot start Volumio Streaming Daemon Dec 30 14:34:28 volumio volumio[6702]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 30 14:34:28 volumio volumio[6702]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 14:34:28 volumio sudo[7825]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:28 volumio volumio[6702]: info: Remote SSH Stopped Dec 30 14:34:28 volumio volumio[6702]: info: Setting Geolocation for MyVolumio to eu10 Dec 30 14:34:28 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:28 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:28 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:29 volumio volumio[6702]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 30 14:34:29 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:29 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:29 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:29 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:29 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:29 volumio volumio[6702]: info: Updating MyVolumio device info Dec 30 14:34:29 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:29 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:29 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:30 volumio volumio[6702]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 30 14:34:31 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 30 14:34:31 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 30 14:34:33 volumio volumio[6702]: info: MYVOLUMIO: Adding device Dec 30 14:34:33 volumio volumio[6702]: info: MYVOLUMIO: Evaluating Server Dec 30 14:34:34 volumio volumio[6702]: info: Setting Geolocation for MyVolumio to eu12 Dec 30 14:34:34 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:34 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:34 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:34 volumio volumio[6702]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 30 14:34:35 volumio volumio[6702]: info: Updating MyVolumio device info Dec 30 14:34:35 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:35 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:35 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 14:34:35 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:35 volumio volumio[6702]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 14:34:35 volumio volumio[6702]: info: CoreStateMachine::ClearQueue Dec 30 14:34:35 volumio volumio[6702]: info: CoreStateMachine::stop Dec 30 14:34:35 volumio volumio[6702]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 14:34:35 volumio volumio[6702]: info: CorePlayQueue::clearPlayQueue Dec 30 14:34:35 volumio volumio[6702]: info: CorePlayQueue::saveQueue Dec 30 14:34:35 volumio volumio[6702]: info: CoreCommandRouter::volumioPushQueue Dec 30 14:34:35 volumio volumio[6702]: info: CoreStateMachine::addQueueItems Dec 30 14:34:35 volumio volumio[6702]: info: CorePlayQueue::addQueueItems Dec 30 14:34:35 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:35 volumio volumio[6702]: info: Adding Item to queue: yandex_music/radio/user:onyourwave Dec 30 14:34:35 volumio volumio[6702]: info: Exploding uri yandex_music/radio/user:onyourwave in service yandex_music Dec 30 14:34:35 volumio volumio[6702]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 30 14:34:36 volumio volumio[6702]: info: CoreCommandRouter::volumioPushQueue Dec 30 14:34:36 volumio volumio[6702]: info: CorePlayQueue::saveQueue Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::updateTrackBlock Dec 30 14:34:36 volumio volumio[6702]: info: CorePlayQueue::getTrackBlock Dec 30 14:34:36 volumio volumio[6702]: info: CoreCommandRouter::volumioPlay Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::play index 0 Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::stop Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::play index undefined Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 14:34:36 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:36 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:36 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 14:34:36 volumio volumio[6702]: info: sendMpdCommand stop took 3 milliseconds Dec 30 14:34:36 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: sendMpdCommand clear took 4 milliseconds Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: error: updateQueue error: null Dec 30 14:34:36 volumio volumio[6702]: error: updateQueue error: null Dec 30 14:34:36 volumio volumio[6702]: error: updateQueue error: null Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 20ms Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 8ms Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 6ms Dec 30 14:34:36 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3" Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3" took 3 milliseconds Dec 30 14:34:36 volumio volumio[6702]: verbose: MPD COMMAND [object Object] Dec 30 14:34:36 volumio volumio[6702]: verbose: MPD COMMAND [object Object] Dec 30 14:34:36 volumio volumio[6702]: verbose: MPD COMMAND [object Object] Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 9ms Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 5ms Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 3ms Dec 30 14:34:36 volumio volumio[6702]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 14:34:36 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand play Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: Dec 30 14:34:36 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:36 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 8ms Dec 30 14:34:36 volumio volumio[6702]: info: sendMpdCommand play took 5 milliseconds Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 5ms Dec 30 14:34:36 volumio volumio[6702]: info: ------------------------------ 4ms Dec 30 14:34:37 volumio volumio[6702]: info: Dec 30 14:34:37 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:37 volumio volumio[6702]: info: Dec 30 14:34:37 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:37 volumio volumio[6702]: info: Dec 30 14:34:37 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand status took 26 milliseconds Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:37 volumio volumio[6702]: info: Dec 30 14:34:37 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:37 volumio volumio[6702]: info: Dec 30 14:34:37 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand status took 30 milliseconds Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand status took 28 milliseconds Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand status took 3 milliseconds Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:37 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:37 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus stop Dec 30 14:34:37 volumio volumio[6702]: info: ------------------------------ 46ms Dec 30 14:34:37 volumio volumio[6702]: info: Dec 30 14:34:37 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand status took 14 milliseconds Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:37 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:37 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:37 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:37 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":604,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:37 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:37 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:37 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:37 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:37 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: ------------------------------ 84ms Dec 30 14:34:37 volumio volumio[6702]: info: ------------------------------ 82ms Dec 30 14:34:37 volumio volumio[6702]: info: ------------------------------ 58ms Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand status took 45 milliseconds Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:37 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:37 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:37 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: ------------------------------ 81ms Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 66 milliseconds Dec 30 14:34:37 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:37 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:37 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:37 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:37 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:37 volumio volumio[6702]: info: ------------------------------ 126ms Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:37 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:37 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:39 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 14:34:39 volumio volumio[6702]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 30 14:34:39 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:34:43 volumio sudo[7910]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 14:34:43 volumio sudo[7908]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 14:34:43 volumio sudo[7910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:43 volumio sudo[7908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:43 volumio sudo[7910]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:43 volumio sudo[7908]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:43 volumio volumio[6702]: verbose: New Socket.io Connection to 192.168.1.73 from 192.168.1.37 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 14:34:43 volumio sudo[7914]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 14:34:43 volumio sudo[7914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:43 volumio sudo[7914]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:43 volumio sudo[7916]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 14:34:43 volumio sudo[7916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 14:34:43 volumio sudo[7916]: pam_unix(sudo:session): session closed for user root Dec 30 14:34:43 volumio volumio[6702]: verbose: New Socket.io Connection to 192.168.1.73 from 192.168.1.37 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 14:34:43 volumio volumio[6702]: info: Listing playlists Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 30 14:34:43 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 14:34:44 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 14:34:44 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 14:34:44 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 30 14:34:44 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 14:34:44 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 30 14:34:44 volumio volumio[6702]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 14:34:44 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 14:34:45 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 14:34:45 volumio volumio[6702]: info: Received Get System Info Dec 30 14:34:45 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 14:34:45 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 14:34:45 volumio volumio[6702]: info: Discovery: Getting this device information Dec 30 14:34:45 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:34:45 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:46 volumio volumio[6702]: info: TRACKBLOCK {"id":"34126089:4188455@user:onyourwave","service":"yandex_music","type":"song","name":"Machines","title":"Machines","album":"Rogue - EP","artist":"Paradise Walk","duration":288,"albumart":"https://avatars.yandex.net/get-music-content/193823/147c5f29.a.4188455-1/200x200","uri":"yandex_music/track/34126089:4188455@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:46 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:46 volumio volumio[6702]: error: null Dec 30 14:34:46 volumio volumio[6702]: info: Dec 30 14:34:46 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:46 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:46 volumio volumio[6702]: info: Dec 30 14:34:46 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:46 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:46 volumio volumio[6702]: info: Dec 30 14:34:46 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:46 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: sendMpdCommand status took 14 milliseconds Dec 30 14:34:46 volumio volumio[6702]: info: sendMpdCommand status took 13 milliseconds Dec 30 14:34:46 volumio volumio[6702]: info: sendMpdCommand status took 12 milliseconds Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:46 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 30 14:34:46 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 30 14:34:46 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:46 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:46 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:46 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:46 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:46 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:46 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:46 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:46 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:46 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:46 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:46 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:46 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:46 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:46 volumio volumio[6702]: info: ------------------------------ 93ms Dec 30 14:34:46 volumio volumio[6702]: info: ------------------------------ 92ms Dec 30 14:34:46 volumio volumio[6702]: info: ------------------------------ 91ms Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 30 14:34:46 volumio volumio[6702]: info: Received Get System Info Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 14:34:46 volumio volumio[6702]: info: Discovery: Getting this device information Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:34:46 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 14:34:47 volumio volumio[6702]: info: VolumeController::SetAlsaVolume75 Dec 30 14:34:47 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:47 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:47 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:47 volumio volumio[6702]: info: VolumeController::SetAlsaVolume63 Dec 30 14:34:47 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:47 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:47 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:47 volumio volumio[6702]: info: VolumeController::SetAlsaVolume57 Dec 30 14:34:47 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:47 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:47 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:47 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:48 volumio volumio[6702]: info: VolumeController::SetAlsaVolume52 Dec 30 14:34:48 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:48 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:48 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:48 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:48 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:48 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: info: TRACKBLOCK {"id":"34126089:4188455@user:onyourwave","service":"yandex_music","type":"song","name":"Machines","title":"Machines","album":"Rogue - EP","artist":"Paradise Walk","duration":288,"albumart":"https://avatars.yandex.net/get-music-content/193823/147c5f29.a.4188455-1/200x200","uri":"yandex_music/track/34126089:4188455@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: error: null Dec 30 14:34:49 volumio volumio[6702]: info: Dec 30 14:34:49 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:49 volumio volumio[6702]: info: Dec 30 14:34:49 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:49 volumio volumio[6702]: info: Dec 30 14:34:49 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand status took 13 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand status took 11 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand status took 9 milliseconds Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:49 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:49 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:49 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:49 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:49 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:49 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: ------------------------------ 80ms Dec 30 14:34:49 volumio volumio[6702]: info: ------------------------------ 78ms Dec 30 14:34:49 volumio volumio[6702]: info: ------------------------------ 76ms Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: info: TRACKBLOCK {"id":"34126089:4188455@user:onyourwave","service":"yandex_music","type":"song","name":"Machines","title":"Machines","album":"Rogue - EP","artist":"Paradise Walk","duration":288,"albumart":"https://avatars.yandex.net/get-music-content/193823/147c5f29.a.4188455-1/200x200","uri":"yandex_music/track/34126089:4188455@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: error: null Dec 30 14:34:49 volumio volumio[6702]: info: Dec 30 14:34:49 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:49 volumio volumio[6702]: info: Dec 30 14:34:49 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:49 volumio volumio[6702]: info: Dec 30 14:34:49 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand status took 12 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand status took 11 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand status took 10 milliseconds Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:49 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:49 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:49 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:49 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:49 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:49 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:49 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:49 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:49 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:49 volumio volumio[6702]: info: ------------------------------ 78ms Dec 30 14:34:49 volumio volumio[6702]: info: ------------------------------ 77ms Dec 30 14:34:49 volumio volumio[6702]: info: ------------------------------ 77ms Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:49 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:49 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: info: TRACKBLOCK {"id":"34126089:4188455@user:onyourwave","service":"yandex_music","type":"song","name":"Machines","title":"Machines","album":"Rogue - EP","artist":"Paradise Walk","duration":288,"albumart":"https://avatars.yandex.net/get-music-content/193823/147c5f29.a.4188455-1/200x200","uri":"yandex_music/track/34126089:4188455@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: error: null Dec 30 14:34:50 volumio volumio[6702]: info: Dec 30 14:34:50 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:50 volumio volumio[6702]: info: Dec 30 14:34:50 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:50 volumio volumio[6702]: info: Dec 30 14:34:50 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand status took 11 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand status took 10 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand status took 9 milliseconds Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:50 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:50 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:50 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:50 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:50 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:50 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: ------------------------------ 81ms Dec 30 14:34:50 volumio volumio[6702]: info: ------------------------------ 80ms Dec 30 14:34:50 volumio volumio[6702]: info: ------------------------------ 80ms Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: info: TRACKBLOCK {"id":"34126089:4188455@user:onyourwave","service":"yandex_music","type":"song","name":"Machines","title":"Machines","album":"Rogue - EP","artist":"Paradise Walk","duration":288,"albumart":"https://avatars.yandex.net/get-music-content/193823/147c5f29.a.4188455-1/200x200","uri":"yandex_music/track/34126089:4188455@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: error: null Dec 30 14:34:50 volumio volumio[6702]: info: Dec 30 14:34:50 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:50 volumio volumio[6702]: info: Dec 30 14:34:50 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:50 volumio volumio[6702]: info: Dec 30 14:34:50 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand status took 12 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand status took 11 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand status took 10 milliseconds Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 14:34:50 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:50 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":155981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:50 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:50 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":155981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:50 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:50 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:50 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":155981,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:50 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:50 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:50 volumio volumio[6702]: info: ------------------------------ 60ms Dec 30 14:34:50 volumio volumio[6702]: info: ------------------------------ 60ms Dec 30 14:34:50 volumio volumio[6702]: info: ------------------------------ 59ms Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:50 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:50 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:53 volumio volumio[6702]: info: TRACKBLOCK {"id":"34126089:4188455@user:onyourwave","service":"yandex_music","type":"song","name":"Machines","title":"Machines","album":"Rogue - EP","artist":"Paradise Walk","duration":288,"albumart":"https://avatars.yandex.net/get-music-content/193823/147c5f29.a.4188455-1/200x200","uri":"yandex_music/track/34126089:4188455@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:53 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:53 volumio volumio[6702]: error: null Dec 30 14:34:53 volumio volumio[6702]: info: Dec 30 14:34:53 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:53 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:53 volumio volumio[6702]: info: Dec 30 14:34:53 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:53 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:53 volumio volumio[6702]: info: Dec 30 14:34:53 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:53 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:53 volumio volumio[6702]: info: sendMpdCommand status took 5 milliseconds Dec 30 14:34:53 volumio volumio[6702]: info: sendMpdCommand status took 3 milliseconds Dec 30 14:34:53 volumio volumio[6702]: info: sendMpdCommand status took 2 milliseconds Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:53 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 30 14:34:53 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 30 14:34:53 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:53 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:53 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:53 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:53 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:53 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:53 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:53 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:53 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:53 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:53 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:53 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206980,"duration":288,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Machines","artist":"Paradise Walk","album":"Rogue - EP","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-165.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D91aa7a8314e4375c86b59ddab3c03be10126503cd54c368d470357c3c6e7231e%2Clid%3D47%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69612643%2F0%2F44051%2F8caae4e6.50928733.7.34126089%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:53 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:53 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:53 volumio volumio[6702]: info: ------------------------------ 68ms Dec 30 14:34:53 volumio volumio[6702]: info: ------------------------------ 67ms Dec 30 14:34:53 volumio volumio[6702]: info: ------------------------------ 67ms Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:53 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:53 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::volumioNext Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::next Dec 30 14:34:54 volumio volumio[6702]: info: ControllerMpd::next Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand next Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand next took 6 milliseconds Dec 30 14:34:54 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:54 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:54 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand status took 3 milliseconds Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand status took 2 milliseconds Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand status took 2 milliseconds Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:54 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 0 Dec 30 14:34:54 volumio volumio[6702]: 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} Dec 30 14:34:54 volumio volumio[6702]: verbose: CURRENT POSITION 0 Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::syncState stateService stop Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::play index undefined Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:54 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Dec 30 14:34:54 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 37ms Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 37ms Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 36ms Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand stop took 24 milliseconds Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 14:34:54 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:54 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand clear took 18 milliseconds Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: error: updateQueue error: null Dec 30 14:34:54 volumio volumio[6702]: error: updateQueue error: null Dec 30 14:34:54 volumio volumio[6702]: error: updateQueue error: null Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 14ms Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 3ms Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 2ms Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3" Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3" took 3 milliseconds Dec 30 14:34:54 volumio volumio[6702]: verbose: MPD COMMAND [object Object] Dec 30 14:34:54 volumio volumio[6702]: verbose: MPD COMMAND [object Object] Dec 30 14:34:54 volumio volumio[6702]: verbose: MPD COMMAND [object Object] Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 10ms Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 6ms Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 5ms Dec 30 14:34:54 volumio volumio[6702]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 14:34:54 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand play Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: Dec 30 14:34:54 volumio volumio[6702]: ---------------------------- MPD announces system playlist update Dec 30 14:34:54 volumio volumio[6702]: info: Ignoring MPD Status Update Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 7ms Dec 30 14:34:54 volumio volumio[6702]: info: sendMpdCommand play took 5 milliseconds Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 4ms Dec 30 14:34:54 volumio volumio[6702]: info: ------------------------------ 3ms Dec 30 14:34:55 volumio volumio[6702]: info: Dec 30 14:34:55 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:55 volumio volumio[6702]: info: Dec 30 14:34:55 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:55 volumio volumio[6702]: info: Dec 30 14:34:55 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand status took 24 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand status took 23 milliseconds Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioAddQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:55 volumio volumio[6702]: info: Adding Item to queue: yandex_music/track/55272412:8132137@user:onyourwave Dec 30 14:34:55 volumio volumio[6702]: info: Exploding uri yandex_music/track/55272412:8132137@user:onyourwave in service yandex_music Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioAddQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:55 volumio volumio[6702]: info: Adding Item to queue: yandex_music/track/98374933:20421077@user:onyourwave Dec 30 14:34:55 volumio volumio[6702]: info: Exploding uri yandex_music/track/98374933:20421077@user:onyourwave in service yandex_music Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioAddQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:55 volumio volumio[6702]: info: Adding Item to queue: yandex_music/track/39356811:7423142@user:onyourwave Dec 30 14:34:55 volumio volumio[6702]: info: Exploding uri yandex_music/track/39356811:7423142@user:onyourwave in service yandex_music Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioAddQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:55 volumio volumio[6702]: info: Adding Item to queue: yandex_music/track/56175475:8293378@user:onyourwave Dec 30 14:34:55 volumio volumio[6702]: info: Exploding uri yandex_music/track/56175475:8293378@user:onyourwave in service yandex_music Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioAddQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::addQueueItems Dec 30 14:34:55 volumio volumio[6702]: info: Preload queue cleared Dec 30 14:34:55 volumio volumio[6702]: info: Adding Item to queue: yandex_music/track/88167030:17125955@user:onyourwave Dec 30 14:34:55 volumio volumio[6702]: info: Exploding uri yandex_music/track/88167030:17125955@user:onyourwave in service yandex_music Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::saveQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::saveQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::saveQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::saveQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::saveQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::updateTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::updateTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::updateTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::updateTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::updateTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrackBlock Dec 30 14:34:55 volumio volumio[6702]: info: Dec 30 14:34:55 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand status took 56 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:55 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hero","artist":"Neal Schon","album":"Voice","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:55 volumio volumio[6702]: verbose: CURRENT POSITION 1 Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus stop Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:55 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hero","artist":"Neal Schon","album":"Voice","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:55 volumio volumio[6702]: verbose: CURRENT POSITION 1 Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:55 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: ------------------------------ 68ms Dec 30 14:34:55 volumio wpa_supplicant[6511]: wlan0: WNM: Preferred List Available Dec 30 14:34:55 volumio volumio[6702]: info: ------------------------------ 81ms Dec 30 14:34:55 volumio volumio[6702]: info: Dec 30 14:34:55 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:55 volumio volumio[6702]: info: Dec 30 14:34:55 volumio volumio[6702]: ---------------------------- MPD announces state update: player Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::getState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand status Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand status took 27 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand status took 3 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand status took 2 milliseconds Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseState Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:55 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hero","artist":"Neal Schon","album":"Voice","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:55 volumio volumio[6702]: verbose: CURRENT POSITION 1 Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:55 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: ------------------------------ 101ms Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 54 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 54 milliseconds Dec 30 14:34:55 volumio volumio[6702]: info: sendMpdCommand playlistinfo took 54 milliseconds Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:55 volumio volumio[6702]: verbose: ControllerMpd::parseTrackInfo Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:55 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hero","artist":"Neal Schon","album":"Voice","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:55 volumio volumio[6702]: verbose: CURRENT POSITION 1 Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:55 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:55 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hero","artist":"Neal Schon","album":"Voice","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:55 volumio volumio[6702]: verbose: CURRENT POSITION 1 Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:55 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: ControllerMpd::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::servicePushState Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:34:55 volumio volumio[6702]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Hero","artist":"Neal Schon","album":"Voice","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-153.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D30c0b703b85cbe01a8ae70e0b919926f886666828550a35ff425de01dd18bc9c%2Clid%3D48%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6961a9d0%2F40879%2F1750975%2F5beb6e1c.582388.11.437479%2F320.mp3&ext=.mp3","trackType":"mp3"} Dec 30 14:34:55 volumio volumio[6702]: verbose: CURRENT POSITION 1 Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState stateService play Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::syncState currentStatus play Dec 30 14:34:55 volumio volumio[6702]: info: Received an update from plugin. extracting info from payload Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:34:55 volumio volumio[6702]: info: ------------------------------ 131ms Dec 30 14:34:55 volumio volumio[6702]: info: ------------------------------ 108ms Dec 30 14:34:55 volumio volumio[6702]: info: ------------------------------ 108ms Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:34:55 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:34:55 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:34:55 volumio wpa_supplicant[6511]: wlan0: SME: Trying to authenticate with 50:ff:20:b4:15:47 (SSID='Fly' freq=5280 MHz) Dec 30 14:34:55 volumio dhcpcd[963]: wlan0: carrier lost Dec 30 14:34:55 volumio kernel: wlan0: disconnect from AP 50:ff:20:b4:15:45 for new auth to 50:ff:20:b4:15:47 Dec 30 14:34:56 volumio avahi-daemon[6650]: Withdrawing address record for 192.168.1.73 on wlan0. Dec 30 14:34:56 volumio avahi-daemon[6650]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.73. Dec 30 14:34:56 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:34:56 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 30 14:34:56 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 30 14:34:56 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 30 14:34:56 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 30 14:34:56 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 30 14:34:56 volumio kernel: wlan0: authenticate with 50:ff:20:b4:15:47 (local address=a0:a3:f0:aa:2c:20) Dec 30 14:34:56 volumio avahi-daemon[6650]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 30 14:34:56 volumio wpa_supplicant[6511]: wlan0: Trying to associate with 50:ff:20:b4:15:47 (SSID='Fly' freq=5280 MHz) Dec 30 14:34:56 volumio dhcpcd[963]: wlan0: deleting route to 192.168.1.0/24 Dec 30 14:34:56 volumio dhcpcd[963]: wlan0: deleting default route via 192.168.1.1 Dec 30 14:34:56 volumio kernel: wlan0: send auth to 50:ff:20:b4:15:47 (try 1/3) Dec 30 14:34:56 volumio kernel: wlan0: authenticated Dec 30 14:34:56 volumio welcome[8021]: Resolved ip:[0] Dec 30 14:34:56 volumio volumio[6702]: info: Discovery: A device disappeared from network Dec 30 14:34:56 volumio volumio[6702]: info: Discovery: Device volumio disappeared from network Dec 30 14:34:56 volumio kernel: wlan0: associate with 50:ff:20:b4:15:47 (try 1/3) Dec 30 14:34:56 volumio kernel: wlan0: RX ReassocResp from 50:ff:20:b4:15:47 (capab=0x1811 status=0 aid=1) Dec 30 14:34:56 volumio kernel: wlan0: associated Dec 30 14:34:56 volumio wpa_supplicant[6511]: wlan0: Associated with 50:ff:20:b4:15:47 Dec 30 14:34:56 volumio wpa_supplicant[6511]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 30 14:34:56 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 30 14:34:56 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:34:56 volumio wpa_supplicant[6511]: wlan0: WPA: Key negotiation completed with 50:ff:20:b4:15:47 [PTK=CCMP GTK=CCMP] Dec 30 14:34:56 volumio wpa_supplicant[6511]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:ff:20:b4:15:47 completed [id=0 id_str=] Dec 30 14:34:56 volumio dhcpcd[963]: wlan0: carrier acquired Dec 30 14:34:56 volumio kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 50:ff:20:b4:15:47 Dec 30 14:34:56 volumio dhcpcd[963]: wlan0: IAID f0:aa:2c:20 Dec 30 14:34:57 volumio dhcpcd[963]: wlan0: soliciting an IPv6 router Dec 30 14:34:58 volumio dhcpcd[963]: wlan0: rebinding lease of 192.168.1.73 Dec 30 14:34:58 volumio dhcpcd[963]: wlan0: probing address 192.168.1.73/24 Dec 30 14:34:58 volumio ntpd[1032]: IO: Deleting interface #9 wlan0, 192.168.1.73#123, interface stats: received=72, sent=72, dropped=5, active_time=42 secs Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 162.159.200.123 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 46.174.178.150 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 178.215.228.24 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 51.68.141.5 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 89.78.89.128 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 46.171.0.154 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 91.212.242.21 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 94.154.96.7 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 91.212.242.19 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 194.146.251.100 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 194.146.251.102 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 162.159.200.1 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 54.37.233.160 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 85.115.212.254 unlink local addr 192.168.1.73 -> Dec 30 14:34:58 volumio ntpd[1032]: PROTO: 195.46.37.22 unlink local addr 192.168.1.73 -> Dec 30 14:35:03 volumio dhcpcd[963]: wlan0: leased 192.168.1.73 for 25200 seconds Dec 30 14:35:03 volumio avahi-daemon[6650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.73. Dec 30 14:35:03 volumio avahi-daemon[6650]: New relevant interface wlan0.IPv4 for mDNS. Dec 30 14:35:03 volumio avahi-daemon[6650]: Registering new address record for 192.168.1.73 on wlan0.IPv4. Dec 30 14:35:03 volumio dhcpcd[963]: wlan0: adding route to 192.168.1.0/24 Dec 30 14:35:04 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:35:04 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 30 14:35:04 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 30 14:35:04 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 30 14:35:04 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 30 14:35:04 volumio dhcpcd[963]: wlan0: adding default route via 192.168.1.1 Dec 30 14:35:04 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 30 14:35:04 volumio welcome[8082]: Resolved ip:[1] 192.168.1.73 Dec 30 14:35:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 30 14:35:04 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 30 14:35:05 volumio ntpd[1032]: IO: Listen normally on 10 wlan0 192.168.1.73:123 Dec 30 14:35:05 volumio ntpd[1032]: IO: new interface(s) found: waking up resolver Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:35:06 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:35:06 volumio volumio[6702]: info: TRACKBLOCK {"id":"437479:45276@user:onyourwave","service":"yandex_music","type":"song","name":"Hero","title":"Hero","album":"Voice","artist":"Neal Schon","duration":262,"albumart":"https://avatars.yandex.net/get-music-content/49876/babf22b1.a.45276-1/200x200","uri":"yandex_music/track/437479:45276@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:35:06 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:35:06 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioSeek Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::seek Dec 30 14:35:06 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:35:06 volumio volumio[6702]: info: TRACKBLOCK {"id":"437479:45276@user:onyourwave","service":"yandex_music","type":"song","name":"Hero","title":"Hero","album":"Voice","artist":"Neal Schon","duration":262,"albumart":"https://avatars.yandex.net/get-music-content/49876/babf22b1.a.45276-1/200x200","uri":"yandex_music/track/437479:45276@user:onyourwave","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::startPlaybackTimer Dec 30 14:35:06 volumio volumio[6702]: info: CorePlayQueue::getTrack 1 Dec 30 14:35:06 volumio volumio[6702]: info: ControllerMpd::seek Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::pushState Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioPushState Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioNext Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::next Dec 30 14:35:06 volumio volumio[6702]: info: ControllerMpd::next Dec 30 14:35:06 volumio volumio[6702]: verbose: ControllerMpd::sendMpdCommand next Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 30 14:35:06 volumio volumio[6702]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 30 14:35:06 volumio volumio[6702]: info: Received Get System Version Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 14:35:06 volumio volumio[6702]: info: Received Get System Info Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 14:35:06 volumio volumio[6702]: info: Discovery: Getting this device information Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioGetState Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:35:06 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:35:06 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:35:06 volumio volumio[6702]: info: CoreCommandRouter::volumioGetQueue Dec 30 14:35:06 volumio volumio[6702]: info: CoreStateMachine::getQueue Dec 30 14:35:06 volumio volumio[6702]: info: CorePlayQueue::getQueue Dec 30 14:35:06 volumio volumio[6702]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 14:35:06 volumio volumio[6702]: Error: read ECONNRESET Dec 30 14:35:06 volumio volumio[6702]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:217:20) { Dec 30 14:35:06 volumio volumio[6702]: errno: -104, Dec 30 14:35:06 volumio volumio[6702]: code: 'ECONNRESET', Dec 30 14:35:06 volumio volumio[6702]: syscall: 'read' Dec 30 14:35:06 volumio volumio[6702]: } Dec 30 14:35:06 volumio volumio[6702]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 14:35:07 volumio sudo[8136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-30 14:34' Dec 30 14:35:07 volumio sudo[8136]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"