Dec 11 22:05:06 volumio volumio[1378]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Dec 11 22:05:06 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Dec 11 22:05:08 volumio volumio[1378]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Dec 11 22:05:08 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Dec 11 22:05:08 volumio sudo[7440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Dec 11 22:05:08 volumio sudo[7440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:08 volumio sudo[7440]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:08 volumio sudo[7442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Dec 11 22:05:08 volumio sudo[7442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:08 volumio sudo[7442]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:08 volumio volumio[1378]: info: Permissions for /etc/network/interfaces set Dec 11 22:05:08 volumio volumio[1378]: info: Restarting Wireless Network Dec 11 22:05:08 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 11 22:05:08 volumio volumio[1378]: info: Discovery: Restarting Advertising due to device name change Dec 11 22:05:08 volumio sudo[7445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 11 22:05:08 volumio sudo[7445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:08 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 11 22:05:08 volumio systemd[1]: wireless.service: Killing process 4193 (wpa_supplicant) with signal SIGKILL. Dec 11 22:05:08 volumio dhcpcd[800]: wlan0: carrier lost Dec 11 22:05:08 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 11 22:05:08 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 11 22:05:08 volumio systemd[1]: wireless.service: Consumed 1.879s CPU time. Dec 11 22:05:08 volumio kernel: wlan0: deauthenticating from 94:83:c4:af:65:a7 by local choice (Reason: 3=DEAUTH_LEAVING) Dec 11 22:05:08 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 11 22:05:08 volumio avahi-daemon[4307]: Withdrawing address record for 192.168.8.157 on wlan0. Dec 11 22:05:08 volumio avahi-daemon[4307]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.8.157. Dec 11 22:05:08 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 11 22:05:08 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 11 22:05:08 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 11 22:05:08 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 11 22:05:08 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 11 22:05:08 volumio avahi-daemon[4307]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 11 22:05:08 volumio dhcpcd[800]: wlan0: deleting route to 192.168.8.0/24 Dec 11 22:05:08 volumio dhcpcd[800]: wlan0: deleting default route via 192.168.8.110 Dec 11 22:05:08 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 11 22:05:08 volumio welcome[7475]: Resolved ip:[0] Dec 11 22:05:08 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 11 22:05:08 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 11 22:05:08 volumio volumio[1378]: info: browseTIDALUri took 56541 milliseconds Dec 11 22:05:08 volumio volumio[1378]: error: TIDAL Browse Error: No response received Dec 11 22:05:08 volumio volumio[1378]: error: Failed to execute browseSource: No response received Dec 11 22:05:08 volumio volumio[1378]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 11 22:05:08 volumio volumio[1378]: info: Retrying Login Due to Network Error Dec 11 22:05:08 volumio volumio[1378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 11 22:05:08 volumio volumio[1378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 11 22:05:08 volumio volumio[1378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 11 22:05:09 volumio wireless.js[7452]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 11 22:05:09 volumio wireless.js[7452]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 11 22:05:09 volumio wireless.js[7452]: WIRELESS.JS: Cleaning previous... Dec 11 22:05:09 volumio sudo[7498]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 11 22:05:09 volumio sudo[7498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:05:09 volumio sudo[7498]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:09 volumio sudo[7500]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 11 22:05:09 volumio sudo[7500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:05:09 volumio sudo[7500]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:09 volumio wireless.js[7452]: WIRELESS.JS: Stopped aP Dec 11 22:05:09 volumio sudo[7508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 11 22:05:09 volumio sudo[7508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:09 volumio sudo[7508]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:09 volumio sudo[7511]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 11 22:05:09 volumio sudo[7511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:09 volumio sudo[7511]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:09 volumio sudo[7519]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 11 22:05:09 volumio sudo[7519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:09 volumio sudo[7519]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:09 volumio sudo[7521]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 11 22:05:09 volumio sudo[7521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:10 volumio sudo[7521]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:10 volumio wireless.js[7452]: WIRELESS.JS: Start wireless flow Dec 11 22:05:10 volumio wireless.js[7452]: WIRELESS.JS: Stopped hotspot (if there).. Dec 11 22:05:10 volumio sudo[7531]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 11 22:05:10 volumio sudo[7531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:05:10 volumio sudo[7531]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:10 volumio sudo[7533]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 11 22:05:10 volumio sudo[7533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:05:10 volumio sudo[7533]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:10 volumio wireless.js[7452]: WIRELESS.JS: DHCP IP fallback Dec 11 22:05:10 volumio wireless.js[7452]: WIRELESS.JS: Start ap Dec 11 22:05:10 volumio wpa_supplicant[7538]: Successfully initialized wpa_supplicant Dec 11 22:05:10 volumio sudo[7539]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 11 22:05:10 volumio sudo[7539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:05:10 volumio dhcpcd[800]: ps_ctl_dispatch: cannot handle another client Dec 11 22:05:10 volumio sudo[7539]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:10 volumio dhcpcd[800]: control_free: No such file or directory Dec 11 22:05:10 volumio volumiossh-tunnel[7391]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Dec 11 22:05:10 volumio autossh[7388]: ssh exited prematurely with status 255; autossh exiting Dec 11 22:05:10 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 11 22:05:10 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Dec 11 22:05:11 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:11 volumio autossh[7543]: port set to 0, monitoring disabled Dec 11 22:05:11 volumio autossh[7543]: starting ssh (count 1) Dec 11 22:05:11 volumio autossh[7543]: ssh child pid is 7546 Dec 11 22:05:11 volumio volumiossh-tunnel[7546]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Dec 11 22:05:11 volumio autossh[7543]: ssh exited prematurely with status 255; autossh exiting Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Dec 11 22:05:11 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:11 volumio autossh[7549]: port set to 0, monitoring disabled Dec 11 22:05:11 volumio autossh[7549]: starting ssh (count 1) Dec 11 22:05:11 volumio autossh[7549]: ssh child pid is 7552 Dec 11 22:05:11 volumio volumiossh-tunnel[7552]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Dec 11 22:05:11 volumio autossh[7549]: ssh exited prematurely with status 255; autossh exiting Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 11 22:05:11 volumio wireless.js[7452]: WIRELESS.JS: trying... Dec 11 22:05:11 volumio sudo[7555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 22:05:11 volumio sudo[7555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:11 volumio sudo[7555]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 6. Dec 11 22:05:11 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:11 volumio autossh[7558]: port set to 0, monitoring disabled Dec 11 22:05:11 volumio autossh[7558]: starting ssh (count 1) Dec 11 22:05:11 volumio autossh[7558]: ssh child pid is 7561 Dec 11 22:05:11 volumio volumiossh-tunnel[7561]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Dec 11 22:05:11 volumio autossh[7558]: ssh exited prematurely with status 255; autossh exiting Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 11 22:05:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 11 22:05:11 volumio wpa_supplicant[7541]: wlan0: SME: Trying to authenticate with 94:83:c4:af:65:a7 (SSID='TechPress_IoT' freq=2437 MHz) Dec 11 22:05:12 volumio kernel: wlan0: authenticate with 94:83:c4:af:65:a7 (local address=70:f1:1c:3b:cd:87) Dec 11 22:05:12 volumio kernel: wlan0: send auth to 94:83:c4:af:65:a7 (try 1/3) Dec 11 22:05:12 volumio wpa_supplicant[7541]: wlan0: Trying to associate with 94:83:c4:af:65:a7 (SSID='TechPress_IoT' freq=2437 MHz) Dec 11 22:05:12 volumio kernel: wlan0: authenticated Dec 11 22:05:12 volumio kernel: wlan0: associate with 94:83:c4:af:65:a7 (try 1/3) Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 7. Dec 11 22:05:12 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:12 volumio kernel: wlan0: RX AssocResp from 94:83:c4:af:65:a7 (capab=0x1431 status=0 aid=7) Dec 11 22:05:12 volumio wpa_supplicant[7541]: wlan0: Associated with 94:83:c4:af:65:a7 Dec 11 22:05:12 volumio wpa_supplicant[7541]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 11 22:05:12 volumio wpa_supplicant[7541]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Dec 11 22:05:12 volumio kernel: wlan0: associated Dec 11 22:05:12 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:12 volumio autossh[7563]: port set to 0, monitoring disabled Dec 11 22:05:12 volumio autossh[7563]: starting ssh (count 1) Dec 11 22:05:12 volumio autossh[7563]: ssh child pid is 7566 Dec 11 22:05:12 volumio volumiossh-tunnel[7566]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Dec 11 22:05:12 volumio autossh[7563]: ssh exited prematurely with status 255; autossh exiting Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 11 22:05:12 volumio wpa_supplicant[7541]: wlan0: WPA: Key negotiation completed with 94:83:c4:af:65:a7 [PTK=CCMP GTK=CCMP] Dec 11 22:05:12 volumio wpa_supplicant[7541]: wlan0: CTRL-EVENT-CONNECTED - Connection to 94:83:c4:af:65:a7 completed [id=0 id_str=] Dec 11 22:05:12 volumio dhcpcd[800]: wlan0: carrier acquired Dec 11 22:05:12 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 94:83:c4:af:65:a7 Dec 11 22:05:12 volumio dhcpcd[800]: wlan0: config file changed, expiring leases Dec 11 22:05:12 volumio dhcpcd[800]: wlan0: IAID 1c:3b:cd:87 Dec 11 22:05:12 volumio dhcpcd[800]: wlan0: probing address 192.168.8.110/24 Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 8. Dec 11 22:05:12 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:12 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:12 volumio autossh[7570]: port set to 0, monitoring disabled Dec 11 22:05:12 volumio autossh[7570]: starting ssh (count 1) Dec 11 22:05:12 volumio autossh[7570]: ssh child pid is 7573 Dec 11 22:05:12 volumio volumiossh-tunnel[7573]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Dec 11 22:05:12 volumio autossh[7570]: ssh exited prematurely with status 255; autossh exiting Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 11 22:05:12 volumio dhcpcd[800]: wlan0: soliciting an IPv6 router Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 9. Dec 11 22:05:12 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Dec 11 22:05:12 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 11 22:05:12 volumio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 22:05:12 volumio volumio[1378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Dec 11 22:05:12 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:12 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:12 volumio wireless.js[7452]: WIRELESS.JS: trying... Dec 11 22:05:12 volumio sudo[7577]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 22:05:12 volumio sudo[7577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:12 volumio sudo[7577]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:13 volumio volumio[1378]: info: Discovery: Started advertising with name: Volumio Dec 11 22:05:13 volumio wireless.js[7452]: WIRELESS.JS: trying... Dec 11 22:05:13 volumio sudo[7583]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 22:05:13 volumio sudo[7583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:13 volumio sudo[7583]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:14 volumio ntpd[1138]: IO: Deleting interface #8 wlan0, 192.168.8.157#123, interface stats: received=0, sent=111, dropped=0, active_time=805 secs Dec 11 22:05:14 volumio ntpd[1138]: PROTO: 103.149.168.181 unlink local addr 192.168.8.157 -> Dec 11 22:05:14 volumio ntpd[1138]: PROTO: 193.123.56.220 unlink local addr 192.168.8.157 -> Dec 11 22:05:14 volumio ntpd[1138]: PROTO: 185.51.192.61 unlink local addr 192.168.8.157 -> Dec 11 22:05:14 volumio ntpd[1138]: PROTO: 81.172.248.188 unlink local addr 192.168.8.157 -> Dec 11 22:05:14 volumio ntpd[1138]: PROTO: 5.255.124.190 unlink local addr 192.168.8.157 -> Dec 11 22:05:14 volumio ntpd[1138]: PROTO: 141.138.201.22 unlink local addr 192.168.8.157 -> Dec 11 22:05:14 volumio ntpd[1138]: PROTO: 172.233.38.176 unlink local addr 192.168.8.157 -> Dec 11 22:05:14 volumio wireless.js[7452]: WIRELESS.JS: trying... Dec 11 22:05:14 volumio sudo[7588]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 22:05:14 volumio sudo[7588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:14 volumio sudo[7588]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:15 volumio wireless.js[7452]: WIRELESS.JS: trying... Dec 11 22:05:15 volumio sudo[7593]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 22:05:15 volumio sudo[7593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:15 volumio sudo[7593]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:16 volumio volumio[1378]: info: Volumio Network Manager: Network status updated: 0 Dec 11 22:05:16 volumio wireless.js[7452]: WIRELESS.JS: trying... Dec 11 22:05:16 volumio sudo[7612]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 22:05:16 volumio sudo[7612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:16 volumio sudo[7612]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:16 volumio dhcpcd[800]: wlan0: using static address 192.168.8.110/24 Dec 11 22:05:16 volumio avahi-daemon[4307]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.8.110. Dec 11 22:05:16 volumio avahi-daemon[4307]: New relevant interface wlan0.IPv4 for mDNS. Dec 11 22:05:16 volumio avahi-daemon[4307]: Registering new address record for 192.168.8.110 on wlan0.IPv4. Dec 11 22:05:16 volumio dhcpcd[800]: wlan0: adding route to 192.168.8.0/24 Dec 11 22:05:16 volumio dhcpcd[800]: wlan0: adding default route via 192.168.8.1 Dec 11 22:05:16 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 11 22:05:16 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 11 22:05:16 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 11 22:05:16 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 11 22:05:16 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 11 22:05:16 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 11 22:05:16 volumio welcome[7625]: Resolved ip:[1] 192.168.8.110 Dec 11 22:05:16 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 11 22:05:16 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 11 22:05:17 volumio wireless.js[7452]: WIRELESS.JS: trying... Dec 11 22:05:17 volumio sudo[7641]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 22:05:17 volumio sudo[7641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:17 volumio sudo[7641]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:17 volumio wireless.js[7452]: WIRELESS.JS: Connected to: ----TechPress_IoT---- Dec 11 22:05:17 volumio sudo[7644]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 22:05:17 volumio sudo[7644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:05:17 volumio sudo[7644]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:17 volumio wireless.js[7452]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.8.110, ipV6 is undefined Dec 11 22:05:17 volumio wireless.js[7452]: WIRELESS.JS: It's done! AP Dec 11 22:05:17 volumio wireless.js[7452]: WIRELESS.JS: Restarting avahi-daemon... Dec 11 22:05:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 22:05:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 22:05:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 22:05:17 volumio systemd[1]: shairport-sync.service: Consumed 17.426s CPU time. Dec 11 22:05:17 volumio avahi-daemon[4307]: Got SIGTERM, quitting. Dec 11 22:05:17 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 11 22:05:17 volumio avahi-daemon[4307]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.8.110. Dec 11 22:05:17 volumio avahi-daemon[4307]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 11 22:05:17 volumio avahi-daemon[4307]: avahi-daemon 0.8 exiting. Dec 11 22:05:17 volumio volumio[1378]: error: Discovery: Advertisement error: Error: dns service error: unknown Dec 11 22:05:17 volumio volumio[1378]: info: Discovery: mDNS temporarily unavailable during network transition Dec 11 22:05:17 volumio dbus-daemon[737]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.44' (uid=0 pid=1363 comm="/usr/sbin/smbd --foreground --no-process-group") Dec 11 22:05:17 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Dec 11 22:05:17 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 11 22:05:17 volumio wireless.js[7452]: WIRELESS.JS: Notified systemd about wireless ready Dec 11 22:05:17 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 11 22:05:17 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 11 22:05:17 volumio sudo[7445]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:17 volumio avahi-daemon[7658]: Process 4307 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Dec 11 22:05:17 volumio avahi-daemon[7658]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 11 22:05:17 volumio avahi-daemon[7658]: Successfully dropped root privileges. Dec 11 22:05:17 volumio avahi-daemon[7658]: avahi-daemon 0.8 starting up. Dec 11 22:05:17 volumio dbus-daemon[737]: [system] Successfully activated service 'org.freedesktop.Avahi' Dec 11 22:05:17 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 11 22:05:17 volumio avahi-daemon[7658]: Successfully called chroot(). Dec 11 22:05:17 volumio avahi-daemon[7658]: Successfully dropped remaining capabilities. Dec 11 22:05:17 volumio avahi-daemon[7658]: No service file found in /etc/avahi/services. Dec 11 22:05:17 volumio avahi-daemon[7658]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.8.110. Dec 11 22:05:17 volumio avahi-daemon[7658]: New relevant interface wlan0.IPv4 for mDNS. Dec 11 22:05:17 volumio avahi-daemon[7658]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 11 22:05:17 volumio avahi-daemon[7658]: New relevant interface lo.IPv4 for mDNS. Dec 11 22:05:17 volumio avahi-daemon[7658]: Network interface enumeration completed. Dec 11 22:05:17 volumio avahi-daemon[7658]: Registering new address record for 192.168.8.110 on wlan0.IPv4. Dec 11 22:05:17 volumio avahi-daemon[7658]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 11 22:05:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 22:05:18 volumio ntpd[1138]: IO: Listen normally on 9 wlan0 192.168.8.110:123 Dec 11 22:05:18 volumio ntpd[1138]: IO: new interface(s) found: waking up resolver Dec 11 22:05:18 volumio ntpd[1138]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 11 22:05:18 volumio ntpd[1138]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 11 22:05:18 volumio ntpd[1138]: DNS: Pool taking: 178.239.19.57 Dec 11 22:05:18 volumio ntpd[1138]: DNS: Pool taking: 144.21.38.190 Dec 11 22:05:18 volumio ntpd[1138]: DNS: Pool taking: 193.111.32.123 Dec 11 22:05:18 volumio ntpd[1138]: DNS: Pool taking: 94.142.246.192 Dec 11 22:05:18 volumio ntpd[1138]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 11 22:05:18 volumio avahi-daemon[7658]: Server startup complete. Host name is volumio.local. Local service cookie is 4102599264. Dec 11 22:05:18 volumio volumio[1378]: info: MyVolumio login type: Token Dec 11 22:05:19 volumio ntpd[1138]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 11 22:05:19 volumio ntpd[1138]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 45.138.55.62 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 178.215.228.24 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 178.239.19.58 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 185.71.60.123 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 2a02:a44c:c251:4::124 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 2a14:7c1:4bbe::1234 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 2600:3c0e::f03c:94ff:fe24:f6a2 Dec 11 22:05:19 volumio ntpd[1138]: DNS: Pool taking: 2a0f:8cc0:0:32:123::1 Dec 11 22:05:19 volumio ntpd[1138]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 11 22:05:19 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 22:05:19 volumio volumio[1378]: info: Received Get System Info Dec 11 22:05:19 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 22:05:19 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 22:05:19 volumio volumio[1378]: info: Discovery: Getting this device information Dec 11 22:05:19 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:19 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:19 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 22:05:22 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 11 22:05:22 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 11 22:05:22 volumio sudo[7697]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 22:05:22 volumio sudo[7697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:22 volumio sudo[7697]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:22 volumio sudo[7701]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 11 22:05:22 volumio sudo[7701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:22 volumio sudo[7701]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:26 volumio volumio[1378]: info: Volumio Network Manager: Network status updated: 2 Dec 11 22:05:27 volumio volumio[1378]: info: Discovery: Started advertising with name: Volumio Dec 11 22:05:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 11 22:05:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 11 22:05:27 volumio volumio[1378]: info: Refreshing Cached IP Addresses Dec 11 22:05:27 volumio sudo[7727]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 22:05:27 volumio sudo[7727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:27 volumio sudo[7727]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:27 volumio sudo[7729]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 22:05:27 volumio sudo[7729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:27 volumio sudo[7729]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:39 volumio sudo[7762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 22:05:39 volumio sudo[7762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:39 volumio sudo[7762]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:39 volumio sudo[7764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 22:05:39 volumio sudo[7764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:39 volumio sudo[7764]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:39 volumio volumio[1378]: verbose: New Socket.io Connection to 192.168.8.110 from 192.168.8.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 22:05:40 volumio sudo[7768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 22:05:40 volumio sudo[7768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:40 volumio sudo[7770]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 22:05:40 volumio sudo[7770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:05:40 volumio sudo[7768]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:40 volumio sudo[7770]: pam_unix(sudo:session): session closed for user root Dec 11 22:05:40 volumio volumio[1378]: verbose: New Socket.io Connection to 192.168.8.110 from 192.168.8.195 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:40 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 22:05:40 volumio volumio[1378]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 11 22:05:40 volumio volumio[1378]: info: Received Get System Info Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 22:05:40 volumio volumio[1378]: info: Discovery: Getting this device information Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:40 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:40 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:40 volumio volumio[1378]: info: Listing playlists Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 22:05:40 volumio volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 22:05:41 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:41 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:42 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 11 22:05:42 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 22:05:42 volumio volumio[1378]: info: Received Get System Info Dec 11 22:05:42 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 22:05:42 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 22:05:42 volumio volumio[1378]: info: Discovery: Getting this device information Dec 11 22:05:42 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:42 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:42 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 22:05:42 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 22:05:45 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 22:05:45 volumio volumio[1378]: info: Received Get System Info Dec 11 22:05:45 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 22:05:45 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 22:05:45 volumio volumio[1378]: info: Discovery: Getting this device information Dec 11 22:05:45 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:45 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:45 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 22:05:45 volumio volumio[1378]: info: CoreCommandRouter::volumioGetQueue Dec 11 22:05:45 volumio volumio[1378]: info: CoreStateMachine::getQueue Dec 11 22:05:45 volumio volumio[1378]: info: CorePlayQueue::getQueue Dec 11 22:05:46 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 11 22:05:46 volumio volumio[1378]: info: browseTIDALUri took 250 milliseconds Dec 11 22:05:46 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:05:46 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:05:49 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 11 22:05:50 volumio volumio[1378]: info: browseTIDALUri took 216 milliseconds Dec 11 22:05:50 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:05:50 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:05:50 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 11 22:05:57 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Dec 11 22:05:58 volumio volumio[1378]: info: browseTIDALUri took 650 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/314449284 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/156179941 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/175536260 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/260546143 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/374727856 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/203755014 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/176590901 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/228858599 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/237021315 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/248376172 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/217711742 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/214850835 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/158396208 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/252956262 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/67083101 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/116641851 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/179553298 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/255484036 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/118233579 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/301812110 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/334726165 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/274701599 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/243354714 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/184903132 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/322591619 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/64567659 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/204675240 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/276859446 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/149607176 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/456676815 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/176788372 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/61330085 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/32079004 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/52587844 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/436256810 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/434932152 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/79712263 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/200962949 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/150883243 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/87788856 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/425620075 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/352405598 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/278743254 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/411329945 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/445607474 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/421724906 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/309054651 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/182495266 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/84841100 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/452498105 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/182495266 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/84841100 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/452498105 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/22363271 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/29906438 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/68711009 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/63884743 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/427323319 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/128485134 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/441900317 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/449566300 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/77192877 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/198829132 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/433032144 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/359079438 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/353995675 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/344381314 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/304811776 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/77192810 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/77192863 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/77192864 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/77192865 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/77192870 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/77192875 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/294995969 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/46735004 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/360402877 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/79487566 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/96525955 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/282360602 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/90276949 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/54818402 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/273471423 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/398269847 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/328395271 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/259289429 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/465216399 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/447368829 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/195076594 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/181364561 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/220158671 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/221316124 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/150905094 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/174335790 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/197735733 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/105604676 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/86372841 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/230682283 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/249698361 Dec 11 22:05:58 volumio volumio[1378]: info: Preloading song: tidal://song/245040685 Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/314449284 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/156179941 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/175536260 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/260546143 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/374727856 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/203755014 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 252 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/176590901 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/228858599 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/237021315 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/248376172 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 362 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 314 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 265 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 11 22:05:58 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 11 22:05:58 volumio volumio[1378]: info: Received Get System Version Dec 11 22:05:58 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 22:05:58 volumio volumio[1378]: info: Received Get System Info Dec 11 22:05:58 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 22:05:58 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 22:05:58 volumio volumio[1378]: info: Discovery: Getting this device information Dec 11 22:05:58 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:05:58 volumio volumio[1378]: info: CorePlayQueue::getTrack 1 Dec 11 22:05:58 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 428 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 229 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/217711742 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/214850835 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 258 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: Exploding uri tidal://song/158396208 in service tidal Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 151 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 280 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 131 milliseconds Dec 11 22:05:58 volumio volumio[1378]: info: explodeTIDALUri took 233 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/252956262 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 127 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/67083101 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 141 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/116641851 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 133 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/179553298 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 129 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/255484036 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 132 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/118233579 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 127 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/301812110 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/334726165 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/274701599 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 238 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/243354714 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/184903132 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/322591619 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/64567659 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 312 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 369 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/204675240 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/276859446 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 379 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 282 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 335 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 148 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/149607176 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 253 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 205 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 127 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/456676815 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 131 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/176788372 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 122 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/61330085 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 141 milliseconds Dec 11 22:05:59 volumio volumio[1378]: info: Exploding uri tidal://song/32079004 in service tidal Dec 11 22:05:59 volumio volumio[1378]: info: explodeTIDALUri took 134 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/52587844 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 126 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/436256810 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 131 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/434932152 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 125 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/79712263 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 127 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/200962949 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 139 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/150883243 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 123 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/87788856 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/425620075 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/352405598 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/278743254 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 258 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 188 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 144 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 246 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/411329945 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 127 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/445607474 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 128 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/421724906 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 134 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/309054651 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 128 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/182495266 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 134 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/84841100 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 125 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Exploding uri tidal://song/452498105 in service tidal Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 126 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Using cached record of: tidal://song/182495266 Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 149 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Using cached record of: tidal://song/84841100 Dec 11 22:06:00 volumio volumio[1378]: info: explodeTIDALUri took 129 milliseconds Dec 11 22:06:00 volumio volumio[1378]: info: Using cached record of: tidal://song/452498105 Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/22363271 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/29906438 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/68711009 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 134 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/63884743 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/427323319 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 157 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 123 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/128485134 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 129 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/441900317 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 124 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/449566300 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/77192877 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 161 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/198829132 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 179 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 145 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/433032144 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 148 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/359079438 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 130 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/353995675 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/344381314 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 163 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 144 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/304811776 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 134 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/77192810 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 138 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/77192863 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/77192864 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 198 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 150 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/77192865 in service tidal Dec 11 22:06:01 volumio volumio[1378]: info: explodeTIDALUri took 130 milliseconds Dec 11 22:06:01 volumio volumio[1378]: info: Exploding uri tidal://song/77192870 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 142 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/77192875 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 139 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/294995969 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 143 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/46735004 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 134 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/360402877 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 141 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/79487566 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 137 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/96525955 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 130 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/282360602 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/90276949 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/54818402 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/273471423 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 184 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 135 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 237 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/398269847 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 125 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/328395271 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 373 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/259289429 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 168 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 129 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/465216399 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 146 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/447368829 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/195076594 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 156 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 137 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/181364561 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 138 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/220158671 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/221316124 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: explodeTIDALUri took 164 milliseconds Dec 11 22:06:02 volumio volumio[1378]: info: Exploding uri tidal://song/150905094 in service tidal Dec 11 22:06:02 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:06:02 volumio volumio[1378]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 22:06:02 volumio volumio[1378]: info: CoreStateMachine::ClearQueue Dec 11 22:06:02 volumio volumio[1378]: info: CoreStateMachine::stop Dec 11 22:06:02 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:02 volumio volumio[1378]: info: CorePlayQueue::clearPlayQueue Dec 11 22:06:02 volumio volumio[1378]: info: CorePlayQueue::saveQueue Dec 11 22:06:02 volumio volumio[1378]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:06:02 volumio volumio[1378]: info: CoreStateMachine::addQueueItems Dec 11 22:06:02 volumio volumio[1378]: info: CorePlayQueue::addQueueItems Dec 11 22:06:02 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:06:02 volumio volumio[1378]: info: Adding Item to queue: tidal://song/314449284 Dec 11 22:06:02 volumio volumio[1378]: info: Using cached record of: tidal://song/314449284 Dec 11 22:06:02 volumio volumio[1378]: info: Adding Item to queue: tidal://song/156179941 Dec 11 22:06:02 volumio volumio[1378]: info: Using cached record of: tidal://song/156179941 Dec 11 22:06:02 volumio volumio[1378]: info: Adding Item to queue: tidal://song/175536260 Dec 11 22:06:02 volumio volumio[1378]: info: Using cached record of: tidal://song/175536260 Dec 11 22:06:02 volumio volumio[1378]: info: Adding Item to queue: tidal://song/260546143 Dec 11 22:06:02 volumio volumio[1378]: info: Using cached record of: tidal://song/260546143 Dec 11 22:06:02 volumio volumio[1378]: info: Adding Item to queue: tidal://song/374727856 Dec 11 22:06:02 volumio volumio[1378]: info: Using cached record of: tidal://song/374727856 Dec 11 22:06:02 volumio volumio[1378]: info: Adding Item to queue: tidal://song/203755014 Dec 11 22:06:02 volumio volumio[1378]: info: Using cached record of: tidal://song/203755014 Dec 11 22:06:02 volumio volumio[1378]: info: Adding Item to queue: tidal://song/176590901 Dec 11 22:06:02 volumio volumio[1378]: info: Using cached record of: tidal://song/176590901 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/228858599 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/228858599 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/237021315 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/237021315 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/248376172 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/248376172 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/217711742 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/217711742 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/214850835 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/214850835 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/158396208 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/158396208 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/252956262 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/252956262 Dec 11 22:06:03 volumio volumio[1378]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:06:03 volumio volumio[1378]: info: CorePlayQueue::saveQueue Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::updateTrackBlock Dec 11 22:06:03 volumio volumio[1378]: info: CorePlayQueue::getTrackBlock Dec 11 22:06:03 volumio volumio[1378]: info: CoreCommandRouter::volumioPlay Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::play index 13 Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::addQueueItems Dec 11 22:06:03 volumio volumio[1378]: info: CorePlayQueue::addQueueItems Dec 11 22:06:03 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/67083101 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/67083101 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/116641851 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/116641851 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/179553298 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/179553298 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/255484036 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/255484036 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/118233579 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/118233579 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/301812110 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/301812110 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/334726165 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/334726165 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/274701599 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/274701599 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/243354714 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/243354714 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/184903132 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/184903132 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/322591619 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/322591619 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/64567659 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/64567659 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/204675240 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/204675240 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/276859446 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/276859446 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/149607176 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/149607176 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/456676815 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/456676815 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/176788372 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/176788372 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/61330085 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/61330085 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/32079004 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/32079004 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/52587844 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/52587844 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/436256810 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/436256810 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/434932152 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/434932152 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/79712263 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/79712263 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/200962949 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/200962949 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/150883243 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/150883243 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/87788856 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/87788856 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/425620075 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/425620075 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/352405598 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/352405598 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/278743254 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/278743254 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/411329945 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/411329945 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/445607474 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/445607474 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/421724906 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/421724906 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/309054651 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/309054651 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/182495266 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/182495266 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/84841100 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/84841100 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/452498105 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/452498105 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/182495266 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/182495266 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/84841100 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/84841100 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/452498105 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/452498105 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/22363271 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/22363271 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/29906438 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/29906438 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/68711009 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/68711009 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/63884743 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/63884743 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/427323319 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/427323319 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/128485134 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/128485134 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/441900317 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/441900317 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/449566300 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/449566300 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192877 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/77192877 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/198829132 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/198829132 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/433032144 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/433032144 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/359079438 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/359079438 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/353995675 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/353995675 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/344381314 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/344381314 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/304811776 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/304811776 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192810 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/77192810 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192863 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/77192863 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192864 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/77192864 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192865 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/77192865 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192870 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/77192870 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192875 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/77192875 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/294995969 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/294995969 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/46735004 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/46735004 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/360402877 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/360402877 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/79487566 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/79487566 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/96525955 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/96525955 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/282360602 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/282360602 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/90276949 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/90276949 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/54818402 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/54818402 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/273471423 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/273471423 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/398269847 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/398269847 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/328395271 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/328395271 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/259289429 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/259289429 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/465216399 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/465216399 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/447368829 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/447368829 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/195076594 Dec 11 22:06:03 volumio volumio[1378]: info: Using cached record of: tidal://song/195076594 Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/181364561 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/181364561 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/220158671 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/220158671 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/221316124 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/221316124 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/150905094 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/150905094 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/174335790 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/174335790 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/197735733 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/197735733 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/105604676 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/105604676 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/86372841 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/86372841 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/230682283 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/230682283 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/249698361 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/249698361 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/245040685 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/245040685 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/382959668 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/382959668 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/164505150 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/164505150 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/75160675 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/75160675 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/443254641 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/443254641 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/211670665 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/211670665 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/96931507 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/96931507 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/212677051 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/212677051 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/170390815 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/170390815 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/173944191 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/173944191 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/281845232 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/281845232 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/187820449 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/187820449 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/223149351 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/223149351 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/236868229 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/236868229 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/420691687 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/420691687 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/87788864 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/87788864 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/368603871 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/368603871 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/404566776 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/404566776 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/411407932 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/411407932 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/426696932 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/426696932 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/385575249 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/385575249 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/369319127 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/369319127 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/429167230 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/429167230 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/388481778 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/388481778 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/417386860 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/417386860 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: Adding Item to queue: tidal://song/430234768 Dec 11 22:06:03 volumio volumio[1378]: info: Exploding uri tidal://song/430234768 in service tidal Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::stop Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::play index undefined Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:03 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:03 volumio volumio[1378]: info: CoreStateMachine::startPlaybackTimer Dec 11 22:06:03 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:03 volumio volumio[1378]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 22:06:03 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 22:06:03 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 11 22:06:03 volumio volumio[1378]: info: [1765487163103] ControllerTidal::clearAddPlayTrack Dec 11 22:06:03 volumio volumio[1378]: info: Getting stream with soundQuality HI_RES Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 494 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 460 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 412 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 300 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 372 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 310 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 310 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 316 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 518 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 521 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 528 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 547 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 552 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 580 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 586 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 590 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 603 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 627 milliseconds Dec 11 22:06:03 volumio volumio[1378]: info: explodeTIDALUri took 630 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:06:07 volumio volumio[1378]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::ClearQueue Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::stop Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::clearPlayQueue Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::saveQueue Dec 11 22:06:07 volumio volumio[1378]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::addQueueItems Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::addQueueItems Dec 11 22:06:07 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/314449284 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/314449284 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/156179941 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/156179941 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/175536260 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/175536260 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/260546143 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/260546143 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/374727856 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/374727856 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/203755014 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/203755014 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/176590901 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/176590901 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/228858599 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/228858599 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/237021315 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/237021315 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/248376172 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/248376172 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/217711742 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/217711742 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/214850835 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/214850835 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/158396208 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/158396208 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/252956262 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/252956262 Dec 11 22:06:07 volumio volumio[1378]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::saveQueue Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::updateTrackBlock Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::getTrackBlock Dec 11 22:06:07 volumio volumio[1378]: info: CoreCommandRouter::volumioPlay Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::play index 13 Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::addQueueItems Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::addQueueItems Dec 11 22:06:07 volumio volumio[1378]: info: Preload queue cleared Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/67083101 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/67083101 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/116641851 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/116641851 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/179553298 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/179553298 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/255484036 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/255484036 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/118233579 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/118233579 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/301812110 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/301812110 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/334726165 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/334726165 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/274701599 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/274701599 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/243354714 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/243354714 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/184903132 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/184903132 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/322591619 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/322591619 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/64567659 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/64567659 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/204675240 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/204675240 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/276859446 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/276859446 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/149607176 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/149607176 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/456676815 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/456676815 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/176788372 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/176788372 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/61330085 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/61330085 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/32079004 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/32079004 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/52587844 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/52587844 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/436256810 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/436256810 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/434932152 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/434932152 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/79712263 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/79712263 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/200962949 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/200962949 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/150883243 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/150883243 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/87788856 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/87788856 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/425620075 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/425620075 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/352405598 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/352405598 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/278743254 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/278743254 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/411329945 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/411329945 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/445607474 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/445607474 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/421724906 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/421724906 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/309054651 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/309054651 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/182495266 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/182495266 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/84841100 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/84841100 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/452498105 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/452498105 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/182495266 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/182495266 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/84841100 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/84841100 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/452498105 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/452498105 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/22363271 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/22363271 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/29906438 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/29906438 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/68711009 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/68711009 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/63884743 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/63884743 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/427323319 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/427323319 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/128485134 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/128485134 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/441900317 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/441900317 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/449566300 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/449566300 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192877 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/77192877 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/198829132 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/198829132 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/433032144 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/433032144 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/359079438 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/359079438 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/353995675 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/353995675 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/344381314 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/344381314 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/304811776 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/304811776 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192810 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/77192810 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192863 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/77192863 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192864 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/77192864 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192865 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/77192865 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192870 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/77192870 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/77192875 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/77192875 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/294995969 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/294995969 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/46735004 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/46735004 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/360402877 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/360402877 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/79487566 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/79487566 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/96525955 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/96525955 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/282360602 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/282360602 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/90276949 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/90276949 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/54818402 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/54818402 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/273471423 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/273471423 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/398269847 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/398269847 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/328395271 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/328395271 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/259289429 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/259289429 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/465216399 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/465216399 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/447368829 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/447368829 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/195076594 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/195076594 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/181364561 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/181364561 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/220158671 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/220158671 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/221316124 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/221316124 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/150905094 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/150905094 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/174335790 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/174335790 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/197735733 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/197735733 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/105604676 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/105604676 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/86372841 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/86372841 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/230682283 Dec 11 22:06:07 volumio volumio[1378]: info: Using cached record of: tidal://song/230682283 Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/249698361 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/249698361 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/245040685 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/245040685 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/382959668 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/382959668 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/164505150 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/164505150 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/75160675 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/75160675 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/443254641 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/443254641 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/211670665 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/211670665 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/96931507 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/96931507 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/212677051 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/212677051 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/170390815 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/170390815 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/173944191 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/173944191 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/281845232 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/281845232 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/187820449 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/187820449 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/223149351 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/223149351 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/236868229 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/236868229 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/420691687 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/420691687 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/87788864 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/87788864 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/368603871 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/368603871 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/404566776 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/404566776 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/411407932 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/411407932 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/426696932 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/426696932 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/385575249 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/385575249 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/369319127 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/369319127 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/429167230 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/429167230 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/388481778 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/388481778 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/417386860 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/417386860 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: Adding Item to queue: tidal://song/430234768 Dec 11 22:06:07 volumio volumio[1378]: info: Exploding uri tidal://song/430234768 in service tidal Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::stop Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::play index undefined Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:07 volumio volumio[1378]: info: CoreStateMachine::startPlaybackTimer Dec 11 22:06:07 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:07 volumio volumio[1378]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 22:06:07 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 22:06:07 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 11 22:06:07 volumio volumio[1378]: info: [1765487167403] ControllerTidal::clearAddPlayTrack Dec 11 22:06:07 volumio volumio[1378]: info: Getting stream with soundQuality HI_RES Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 256 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 256 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 256 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 260 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 258 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 249 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 262 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 255 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 262 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 265 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 261 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 266 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 265 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 271 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 264 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 273 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 267 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 270 milliseconds Dec 11 22:06:07 volumio volumio[1378]: info: explodeTIDALUri took 276 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5400 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5427 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5428 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5444 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5445 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5473 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5479 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5503 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5553 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5550 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5550 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5566 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5570 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5571 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5598 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5604 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5628 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5680 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5692 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5695 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5710 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5709 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5711 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5712 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5737 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5738 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 5743 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:06:08 volumio volumio[1378]: info: CorePlayQueue::saveQueue Dec 11 22:06:08 volumio volumio[1378]: info: CoreStateMachine::updateTrackBlock Dec 11 22:06:08 volumio volumio[1378]: info: CorePlayQueue::getTrackBlock Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1490 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1491 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1492 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1493 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1499 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1511 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1533 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: explodeTIDALUri took 1552 milliseconds Dec 11 22:06:08 volumio volumio[1378]: info: CoreCommandRouter::volumioPushQueue Dec 11 22:06:08 volumio volumio[1378]: info: CorePlayQueue::saveQueue Dec 11 22:06:08 volumio volumio[1378]: info: CoreStateMachine::updateTrackBlock Dec 11 22:06:08 volumio volumio[1378]: info: CorePlayQueue::getTrackBlock Dec 11 22:06:09 volumio volumio[1378]: info: getStreamUrl took 6067 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand stop took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand clear took 0 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==" Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:09 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==" took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand play Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:09 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:09 volumio volumio[1378]: info: ------------------------------ 5ms Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand play took 3 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: ------------------------------ 3ms Dec 11 22:06:09 volumio volumio[1378]: info: getStreamUrl took 1799 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand stop took 493 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:09 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand status took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand clear took 3 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==" Dec 11 22:06:09 volumio volumio[1378]: error: updateQueue error: null Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:09 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:09 volumio volumio[1378]: info: ------------------------------ 5ms Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==" took 3 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:09 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand play Dec 11 22:06:09 volumio volumio[1378]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 22:06:09 volumio volumio[1378]: info: ------------------------------ 11ms Dec 11 22:06:09 volumio volumio[1378]: info: ------------------------------ 5ms Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand play took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: CoreCommandRouter::volumioPlay Dec 11 22:06:09 volumio volumio[1378]: info: CoreStateMachine::play index undefined Dec 11 22:06:09 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:09 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:09 volumio volumio[1378]: info: CoreStateMachine::startPlaybackTimer Dec 11 22:06:09 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:09 volumio volumio[1378]: info: [1765487169807] ControllerTidal::clearAddPlayTrack Dec 11 22:06:09 volumio volumio[1378]: info: Getting stream with soundQuality HI_RES Dec 11 22:06:09 volumio volumio[1378]: info: getStreamUrl took 181 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand stop took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:09 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand status took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand clear took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==" Dec 11 22:06:09 volumio volumio[1378]: error: updateQueue error: null Dec 11 22:06:09 volumio volumio[1378]: info: Dec 11 22:06:09 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:09 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:09 volumio volumio[1378]: info: ------------------------------ 3ms Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 22:06:09 volumio volumio[1378]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==" took 1 milliseconds Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:09 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:06:09 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand play Dec 11 22:06:09 volumio volumio[1378]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 22:06:09 volumio volumio[1378]: info: ------------------------------ 7ms Dec 11 22:06:10 volumio volumio[1378]: info: ------------------------------ 7ms Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand play took 6 milliseconds Dec 11 22:06:10 volumio volumio[1378]: info: Dec 11 22:06:10 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:10 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:10 volumio volumio[1378]: info: Dec 11 22:06:10 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand status took 16 milliseconds Dec 11 22:06:10 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand status took 3 milliseconds Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:10 volumio volumio[1378]: info: ControllerMpd::pushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:10 volumio volumio[1378]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==","trackType":"tidal"} Dec 11 22:06:10 volumio volumio[1378]: verbose: CURRENT POSITION 13 Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:06:10 volumio volumio[1378]: info: ------------------------------ 25ms Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:10 volumio volumio[1378]: info: ControllerMpd::pushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:10 volumio volumio[1378]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMjMxYjQxZWJmMTM3Zjg2Zjk5YzQ3YTkwZmM2MzRkYl82MS5tcDQ/0.flac?token=1765490769~YjAwMjg5NzY0NjExZjdkMTBhZDc1YmFjMzhiMjZhYzYyZGIwYWFiZg==","trackType":"tidal"} Dec 11 22:06:10 volumio volumio[1378]: verbose: CURRENT POSITION 13 Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:06:10 volumio volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:10 volumio volumio[1378]: info: ------------------------------ 36ms Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - volume level for loudness 30 gain applied 6.57 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - volume level for loudness 30 gain applied 6.57 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::volumioNext Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::next Dec 11 22:06:10 volumio volumio[1378]: info: [1765487170652] ControllerTidal::next Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::next Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::stop Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::updateTrackBlock Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrackBlock Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::serviceStop Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 13 Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::serviceStop Dec 11 22:06:10 volumio volumio[1378]: info: [1765487170666] ControllerTidal::stop Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:06:10 volumio volumio[1378]: info: ControllerMpd::stop Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - volume level for loudness 30 gain applied 6.57 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:10 volumio volumio[1378]: info: Dec 11 22:06:10 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand stop took 22 milliseconds Dec 11 22:06:10 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::play index undefined Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::startPlaybackTimer Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:10 volumio volumio[1378]: info: [1765487170691] ControllerTidal::clearAddPlayTrack Dec 11 22:06:10 volumio volumio[1378]: info: Getting stream with soundQuality HI_RES Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::updateTrackBlock Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrackBlock Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand status took 6 milliseconds Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:10 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 11 22:06:10 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:10 volumio volumio[1378]: info: ControllerMpd::pushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:10 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:10 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:10 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:10 volumio volumio[1378]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Dec 11 22:06:10 volumio volumio[1378]: info: ------------------------------ 26ms Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - volume level for loudness 30 gain applied 6.57 Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:10 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:11 volumio volumio[1378]: info: getStreamUrl took 332 milliseconds Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand stop took 1 milliseconds Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 22:06:11 volumio volumio[1378]: info: Dec 11 22:06:11 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:11 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand clear took 2 milliseconds Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MmQ0YzA3MWNiOGQyOWYxNGQ2ZDVhYTY5YjM4NTk2MV82MS5tcDQ/0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==" Dec 11 22:06:11 volumio volumio[1378]: error: updateQueue error: null Dec 11 22:06:11 volumio volumio[1378]: info: Dec 11 22:06:11 volumio volumio[1378]: ---------------------------- MPD announces system playlist update Dec 11 22:06:11 volumio volumio[1378]: info: Ignoring MPD Status Update Dec 11 22:06:11 volumio volumio[1378]: info: ------------------------------ 3ms Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MmQ0YzA3MWNiOGQyOWYxNGQ2ZDVhYTY5YjM4NTk2MV82MS5tcDQ/0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==" took 2 milliseconds Dec 11 22:06:11 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand play Dec 11 22:06:11 volumio volumio[1378]: info: ------------------------------ 2ms Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand play took 2 milliseconds Dec 11 22:06:11 volumio volumio[1378]: info: Dec 11 22:06:11 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:11 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:11 volumio volumio[1378]: info: Dec 11 22:06:11 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand status took 11 milliseconds Dec 11 22:06:11 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand status took 2 milliseconds Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:11 volumio volumio[1378]: info: ControllerMpd::pushState Dec 11 22:06:11 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:11 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:11 volumio volumio[1378]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MmQ0YzA3MWNiOGQyOWYxNGQ2ZDVhYTY5YjM4NTk2MV82MS5tcDQ/0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==","trackType":"tidal"} Dec 11 22:06:11 volumio volumio[1378]: verbose: CURRENT POSITION 14 Dec 11 22:06:11 volumio volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 11 22:06:11 volumio volumio[1378]: info: CoreStateMachine::syncState currentStatus stop Dec 11 22:06:11 volumio volumio[1378]: info: ------------------------------ 15ms Dec 11 22:06:11 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 22:06:11 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:11 volumio volumio[1378]: info: ControllerMpd::pushState Dec 11 22:06:11 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:11 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:11 volumio volumio[1378]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MmQ0YzA3MWNiOGQyOWYxNGQ2ZDVhYTY5YjM4NTk2MV82MS5tcDQ/0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==","trackType":"tidal"} Dec 11 22:06:11 volumio volumio[1378]: verbose: CURRENT POSITION 14 Dec 11 22:06:11 volumio volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 11 22:06:11 volumio volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 11 22:06:11 volumio volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 11 22:06:11 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:11 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:11 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:11 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:11 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:11 volumio volumio[1378]: info: ------------------------------ 27ms Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - volume level for loudness 30 gain applied 6.57 Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - volume level for loudness 30 gain applied 6.57 Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:11 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:16 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 22:06:16 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 22:06:16 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 22:06:16 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 22:06:16 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 22:06:16 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 22:06:25 volumio kernel: usb 1-4: USB disconnect, device number 4 Dec 11 22:06:25 volumio volumio[1378]: info: Dec 11 22:06:25 volumio volumio[1378]: ---------------------------- USB Audio Device Detached Dec 11 22:06:25 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 11 22:06:25 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:25 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 11 22:06:25 volumio kernel: perf: interrupt took too long (3163 > 3132), lowering kernel.perf_event_max_sample_rate to 63000 Dec 11 22:06:26 volumio kernel: usb 1-4: new high-speed USB device number 5 using xhci_hcd Dec 11 22:06:26 volumio kernel: usb 1-4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Dec 11 22:06:26 volumio kernel: usb 1-4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 11 22:06:26 volumio kernel: usb 1-4: Product: SMSL USB AUDIO Dec 11 22:06:26 volumio kernel: usb 1-4: Manufacturer: SMSL Dec 11 22:06:26 volumio kernel: hid-generic 0003:152A:85DD.0002: hiddev0,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:00:14.0-4/input3 Dec 11 22:06:26 volumio volumio[1378]: info: Dec 11 22:06:26 volumio volumio[1378]: ---------------------------- USB Audio Device Attached Dec 11 22:06:26 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 11 22:06:26 volumio volumio[1378]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:06:26 volumio volumio[1378]: info: Preparing to save Alsa Options, stopping services first Dec 11 22:06:26 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:06:26 volumio volumio[1378]: info: CoreCommandRouter::volumioPause Dec 11 22:06:26 volumio volumio[1378]: info: CoreStateMachine::pause Dec 11 22:06:26 volumio volumio[1378]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:06:26 volumio volumio[1378]: info: CoreStateMachine::servicePause Dec 11 22:06:26 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:26 volumio volumio[1378]: info: CoreCommandRouter::servicePause Dec 11 22:06:26 volumio volumio[1378]: info: [1765487186916] ControllerTidal::pause Dec 11 22:06:26 volumio volumio[1378]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 22:06:26 volumio volumio[1378]: info: ControllerMpd::pause Dec 11 22:06:26 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand pause Dec 11 22:06:26 volumio volumio[1378]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 11 22:06:26 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:26 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:26 volumio volumio[1378]: info: Setting default mixerSoftMaster for Softvolume device Dec 11 22:06:26 volumio vtcs[2144]: [2025-12-11 22:06:26.986] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 11 22:06:26 volumio vtcs[2144]: [2025-12-11 22:06:26.987] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 11 22:06:26 volumio vtcs[2144]: [2025-12-11 22:06:26.987] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 11 22:06:27 volumio sudo[8387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:27 volumio sudo[8387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 22:06:27 volumio volumio[1378]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 22:06:27 volumio volumio[1378]: info: Disabling external Volume Control Dec 11 22:06:27 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 22:06:27 volumio systemd[1]: vtcs.service: Killing process 2152 (vtcs) with signal SIGKILL. Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 22:06:27 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 22:06:27 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 22:06:27 volumio systemd[1]: vtcs.service: Consumed 2.407s CPU time. Dec 11 22:06:27 volumio volumio[1378]: info: Enable softmixer device for audio device undefined Dec 11 22:06:27 volumio sudo[8387]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio volumio[1378]: info: Dec 11 22:06:27 volumio volumio[1378]: ---------------------------- MPD announces state update: player Dec 11 22:06:27 volumio volumio[1378]: info: sendMpdCommand pause took 168 milliseconds Dec 11 22:06:27 volumio volumio[1378]: info: ControllerMpd::getState Dec 11 22:06:27 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:06:27 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:27 volumio sudo[8401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:27 volumio sudo[8401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio volumio[1378]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Dec 11 22:06:27 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:27 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:27 volumio volumio[1378]: info: sendMpdCommand status took 46 milliseconds Dec 11 22:06:27 volumio volumio[1378]: verbose: ControllerMpd::parseState Dec 11 22:06:27 volumio volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:06:27 volumio volumio[1378]: info: Software Volume ALSA configuration written Dec 11 22:06:27 volumio volumio[1378]: info: Preparing to generate the ALSA configuration file Dec 11 22:06:27 volumio sudo[8401]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio volumio[1378]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 11 22:06:27 volumio volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:06:27 volumio volumio[1378]: info: ControllerMpd::pushState Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:27 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:27 volumio volumio[1378]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16971,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"758 Kbps","isStreaming":false,"title":"0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MmQ0YzA3MWNiOGQyOWYxNGQ2ZDVhYTY5YjM4NTk2MV82MS5tcDQ/0.flac?token=1765490770~Yzc4ZTBhY2RkYjdmMGJlYzk4OTBjZDk2ODFjMmIwZGIxYTE3ZDJjOA==","trackType":"tidal"} Dec 11 22:06:27 volumio volumio[1378]: verbose: CURRENT POSITION 14 Dec 11 22:06:27 volumio volumio[1378]: info: CoreStateMachine::syncState stateService pause Dec 11 22:06:27 volumio volumio[1378]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:06:27 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:27 volumio volumio[1378]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:06:27 volumio volumio[1378]: info: ------------------------------ 92ms Dec 11 22:06:27 volumio volumio[1378]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 11 22:06:27 volumio volumio[1378]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 11 22:06:27 volumio volumio[1378]: info: Reading ALSA contributions from plugins. Dec 11 22:06:27 volumio volumio[1378]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Dec 11 22:06:27 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:27 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 22:06:27 volumio volumio[1378]: info: Asound.conf file unchanged, so no further update is needed Dec 11 22:06:27 volumio volumio[1378]: info: Output device has changed, restarting MPD Dec 11 22:06:27 volumio volumio[1378]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio sudo[8414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 22:06:27 volumio sudo[8414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio sudo[8416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 22:06:27 volumio sudo[8414]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio sudo[8416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 22:06:27 volumio sudo[8422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:27 volumio sudo[8422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio volumio[1378]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 22:06:27 volumio volumio[1378]: info: MPD Permissions set Dec 11 22:06:27 volumio sudo[8424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:27 volumio sudo[8424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 22:06:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 22:06:27 volumio systemd[1]: mpd.service: Consumed 3.253s CPU time. Dec 11 22:06:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 22:06:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 22:06:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 22:06:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 22:06:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: Preparing to generate the ALSA configuration file Dec 11 22:06:27 volumio volumio[1378]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 11 22:06:27 volumio volumio[1378]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 11 22:06:27 volumio volumio[1378]: info: Reading ALSA contributions from plugins. Dec 11 22:06:27 volumio volumio[1378]: info: Asound.conf file unchanged, so no further update is needed Dec 11 22:06:27 volumio volumio[1378]: info: Output device has changed, restarting MPD Dec 11 22:06:27 volumio sudo[8422]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio sudo[8424]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio volumio[1378]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio sudo[8442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 22:06:27 volumio sudo[8442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio sudo[8438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 22:06:27 volumio sudo[8438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio sudo[8446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:27 volumio sudo[8446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio volumio[1378]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 22:06:27 volumio sudo[8438]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio sudo[8436]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 22:06:27 volumio sudo[8436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:06:27 volumio volumio[1378]: info: MPD Permissions set Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio sudo[8451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:27 volumio sudo[8436]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio sudo[8451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:27 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 22:06:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 22:06:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 22:06:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 22:06:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:27 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 11 22:06:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 22:06:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 22:06:27 volumio sudo[8446]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio sudo[8451]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:27 volumio sudo[8463]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 22:06:27 volumio sudo[8463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:06:27 volumio sudo[8463]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:28 volumio mpd[8467]: 2025-12-11T22:06:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 22:06:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 22:06:28 volumio sudo[8416]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:28 volumio sudo[8442]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:28 volumio volumio[1378]: error: MPD error: The expression evaluated to a falsy value: Dec 11 22:06:28 volumio volumio[1378]: assert.ok(self.idling) Dec 11 22:06:28 volumio volumio[1378]: error: The expression evaluated to a falsy value: Dec 11 22:06:28 volumio volumio[1378]: assert.ok(self.idling) Dec 11 22:06:28 volumio volumio[1378]: error: updateQueue error: null Dec 11 22:06:30 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:30 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:30 volumio volumio[1378]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:30 volumio volumio[1378]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:30 volumio sudo[8489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 22:06:30 volumio sudo[8489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:30 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 22:06:30 volumio sudo[8489]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:30 volumio volumio[1378]: info: Executing endpoint tc_getconfig Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 22:06:30 volumio vtcs[8492]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 11 22:06:30 volumio vtcs[8492]: STARTED TidalConnect services. Dec 11 22:06:30 volumio volumio[1378]: info: Executing endpoint tc_connect Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 22:06:30 volumio volumio[1378]: info: Connecting to TidalConnect Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:30 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:30 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:30 volumio volumio[1378]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:30 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:30 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:30 volumio volumio[1378]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 11 22:06:30 volumio volumio[1378]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Dec 11 22:06:30 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:30 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:30 volumio volumio[1378]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Dec 11 22:06:30 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:30 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:30 volumio volumio[1378]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 11 22:06:30 volumio volumio[1378]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 11 22:06:30 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:30 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:30 volumio volumio[1378]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:30 volumio volumio[1378]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:30 volumio sudo[8519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 22:06:30 volumio sudo[8519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:30 volumio sudo[8519]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:30 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:30 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:30 volumio volumio[1378]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:30 volumio volumio[1378]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:30 volumio sudo[8531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 22:06:30 volumio sudo[8531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:30 volumio kernel: usb 1-4: USB disconnect, device number 5 Dec 11 22:06:30 volumio sudo[8531]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:30 volumio volumio[1378]: info: Dec 11 22:06:30 volumio volumio[1378]: ---------------------------- USB Audio Device Detached Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:30 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 22:06:31 volumio volumio[1378]: info: CoreStateMachine::getcurrentVolume Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 22:06:31 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:31 volumio volumio[1378]: info: CoreStateMachine::updateTrackBlock Dec 11 22:06:31 volumio volumio[1378]: info: CorePlayQueue::getTrackBlock Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - volume level for loudness gain applied 23.00 Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:31 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - volume level for loudness gain applied 23.00 Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:31 volumio volumio[1378]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 11 22:06:31 volumio volumio[1378]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 11 22:06:31 volumio volumio[1378]: info: VolumeController::SetAlsaVolume0 Dec 11 22:06:31 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - volume level for loudness 0 gain applied 23.00 Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:31 volumio volumio[1378]: info: VolumeController::SetAlsaVolume0 Dec 11 22:06:31 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:31 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:31 volumio volumio[1378]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - volume level for loudness 0 gain applied 23.00 Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:31 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:31 volumio volumio[1378]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 11 22:06:31 volumio volumio[1378]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 11 22:06:31 volumio volumio[1378]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 11 22:06:31 volumio sudo[8559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 22:06:31 volumio sudo[8559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:31 volumio sudo[8561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 22:06:31 volumio sudo[8561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:31 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 22:06:31 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 22:06:31 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 22:06:31 volumio systemd[1]: mpd_monitor.service: Consumed 1.242s CPU time. Dec 11 22:06:31 volumio kernel: usb 1-4: new high-speed USB device number 6 using xhci_hcd Dec 11 22:06:31 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 22:06:31 volumio sudo[8561]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:31 volumio sudo[8559]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:31 volumio mpd_monitor.sh[8564]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 22:06:31 volumio volumio[1378]: info: Successfully started MPD Monitor Dec 11 22:06:31 volumio volumio[1378]: info: Successfully started MPD Monitor Dec 11 22:06:31 volumio kernel: usb 1-4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Dec 11 22:06:31 volumio kernel: usb 1-4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 11 22:06:31 volumio kernel: usb 1-4: Product: SMSL USB AUDIO Dec 11 22:06:31 volumio kernel: usb 1-4: Manufacturer: SMSL Dec 11 22:06:32 volumio kernel: hid-generic 0003:152A:85DD.0003: hiddev0,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:00:14.0-4/input3 Dec 11 22:06:32 volumio volumio[1378]: info: Dec 11 22:06:32 volumio volumio[1378]: ---------------------------- USB Audio Device Attached Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:06:32 volumio volumio[1378]: info: Preparing to save Alsa Options, stopping services first Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::volumioPause Dec 11 22:06:32 volumio volumio[1378]: info: CoreStateMachine::pause Dec 11 22:06:32 volumio volumio[1378]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:32 volumio volumio[1378]: info: Setting default mixerSoftMaster for Softvolume device Dec 11 22:06:32 volumio vtcs[8492]: [2025-12-11 22:06:32.766] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 11 22:06:32 volumio vtcs[8492]: [2025-12-11 22:06:32.769] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 11 22:06:32 volumio sudo[8587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:32 volumio sudo[8587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 22:06:32 volumio volumio[1378]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 22:06:32 volumio volumio[1378]: info: Disabling external Volume Control Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 22:06:32 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 22:06:32 volumio systemd[1]: vtcs.service: Killing process 8500 (vtcs) with signal SIGKILL. Dec 11 22:06:32 volumio volumio[1378]: info: Enable softmixer device for audio device undefined Dec 11 22:06:32 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 22:06:32 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 22:06:32 volumio sudo[8587]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:32 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:32 volumio sudo[8601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:32 volumio sudo[8601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:32 volumio volumio[1378]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Dec 11 22:06:32 volumio volumio[1378]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 22:06:32 volumio volumio[1378]: info: FusionDsp - Loudness is ON true Dec 11 22:06:32 volumio volumio[1378]: info: Software Volume ALSA configuration written Dec 11 22:06:32 volumio volumio[1378]: info: Preparing to generate the ALSA configuration file Dec 11 22:06:32 volumio volumio[1378]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 11 22:06:32 volumio sudo[8601]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 22:06:32 volumio volumio[1378]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 11 22:06:32 volumio volumio[1378]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 11 22:06:32 volumio volumio[1378]: info: Reading ALSA contributions from plugins. Dec 11 22:06:32 volumio volumio[1378]: info: Asound.conf file unchanged, so no further update is needed Dec 11 22:06:32 volumio volumio[1378]: info: Output device has changed, restarting MPD Dec 11 22:06:32 volumio volumio[1378]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 22:06:32 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:32 volumio sudo[8612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 22:06:32 volumio sudo[8612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio sudo[8612]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:33 volumio sudo[8614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 22:06:33 volumio sudo[8614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 22:06:33 volumio sudo[8620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:33 volumio sudo[8620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio volumio[1378]: info: MPD Permissions set Dec 11 22:06:33 volumio sudo[8622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio sudo[8622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 22:06:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 22:06:33 volumio systemd[1]: mpd.service: Consumed 1.152s CPU time. Dec 11 22:06:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 22:06:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 22:06:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 22:06:33 volumio volumio[1378]: info: Preparing to generate the ALSA configuration file Dec 11 22:06:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 22:06:33 volumio volumio[1378]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 11 22:06:33 volumio volumio[1378]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 11 22:06:33 volumio volumio[1378]: info: Reading ALSA contributions from plugins. Dec 11 22:06:33 volumio volumio[1378]: info: Asound.conf file unchanged, so no further update is needed Dec 11 22:06:33 volumio volumio[1378]: info: Output device has changed, restarting MPD Dec 11 22:06:33 volumio sudo[8620]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:33 volumio volumio[1378]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio sudo[8622]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:33 volumio sudo[8636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 22:06:33 volumio sudo[8636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio sudo[8636]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:33 volumio sudo[8639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 22:06:33 volumio sudo[8639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio sudo[8646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:33 volumio sudo[8646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio volumio[1378]: info: MPD Permissions set Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio sudo[8648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 22:06:33 volumio sudo[8648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:33 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 22:06:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 22:06:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 22:06:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 22:06:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 22:06:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 22:06:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 22:06:33 volumio sudo[8648]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:33 volumio volumio[1378]: info: TidalConnect service started! Dec 11 22:06:33 volumio sudo[8646]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:33 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 11 22:06:33 volumio sudo[8660]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 22:06:33 volumio sudo[8660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 22:06:33 volumio sudo[8660]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:33 volumio volumio[1378]: info: TidalConnect service started! Dec 11 22:06:33 volumio volumio[1378]: info: TidalConnect service started! Dec 11 22:06:34 volumio mpd[8664]: 2025-12-11T22:06:34 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 22:06:34 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 22:06:34 volumio sudo[8639]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:34 volumio sudo[8614]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:34 volumio volumio[1378]: error: MPD error: The expression evaluated to a falsy value: Dec 11 22:06:34 volumio volumio[1378]: assert.ok(self.idling) Dec 11 22:06:34 volumio volumio[1378]: error: The expression evaluated to a falsy value: Dec 11 22:06:34 volumio volumio[1378]: assert.ok(self.idling) Dec 11 22:06:34 volumio volumio[1378]: error: updateQueue error: null Dec 11 22:06:35 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:35 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:35 volumio volumio[1378]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:35 volumio volumio[1378]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:35 volumio sudo[8679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 22:06:35 volumio sudo[8679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:06:36 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 22:06:36 volumio sudo[8679]: pam_unix(sudo:session): session closed for user root Dec 11 22:06:36 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:36 volumio volumio[1378]: info: TidalConnect service stoped! Dec 11 22:06:36 volumio volumio[1378]: info: Executing endpoint tc_getconfig Dec 11 22:06:36 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 22:06:36 volumio vtcs[8682]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 11 22:06:36 volumio vtcs[8682]: STARTED TidalConnect services. Dec 11 22:06:36 volumio volumio[1378]: info: Executing endpoint tc_connect Dec 11 22:06:36 volumio volumio[1378]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 22:06:36 volumio volumio[1378]: info: Connecting to TidalConnect Dec 11 22:06:36 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:36 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:36 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:36 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:36 volumio volumio[1378]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 11 22:06:36 volumio volumio[1378]: info: CoreCommandRouter::servicePushState Dec 11 22:06:36 volumio volumio[1378]: info: CoreStateMachine::pushState Dec 11 22:06:36 volumio volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 11 22:06:36 volumio volumio[1378]: info: CorePlayQueue::getTrack 14 Dec 11 22:06:36 volumio volumio[1378]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 11 22:06:36 volumio volumio[1378]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:36 volumio volumio[1378]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 22:06:36 volumio volumio[1378]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 22:06:36 volumio volumio[1378]: Error: ENOENT: no such file or directory, unlink '/tmp/XTwjEa3OP9TDJz0x8NWnwLo14bL5r6Q0QYIUIu08w067iZMn2jCGQc4jl6aKP4SyUDSNUSVfM3LZXV1xh45a7vyQRv21CPgQHO66' Dec 11 22:06:36 volumio volumio[1378]: at Object.unlinkSync (node:fs:1899:3) Dec 11 22:06:36 volumio volumio[1378]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Dec 11 22:06:36 volumio volumio[1378]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Dec 11 22:06:36 volumio volumio[1378]: at TidalConnectClient.emit (node:events:514:28) Dec 11 22:06:36 volumio volumio[1378]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Dec 11 22:06:36 volumio volumio[1378]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Dec 11 22:06:36 volumio volumio[1378]: at Array.forEach () Dec 11 22:06:36 volumio volumio[1378]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Dec 11 22:06:36 volumio volumio[1378]: at Socket.emit (node:events:514:28) Dec 11 22:06:36 volumio volumio[1378]: at addChunk (node:internal/streams/readable:343:12) { Dec 11 22:06:36 volumio volumio[1378]: errno: -2, Dec 11 22:06:36 volumio volumio[1378]: syscall: 'unlink', Dec 11 22:06:36 volumio volumio[1378]: code: 'ENOENT', Dec 11 22:06:36 volumio volumio[1378]: path: '/tmp/XTwjEa3OP9TDJz0x8NWnwLo14bL5r6Q0QYIUIu08w067iZMn2jCGQc4jl6aKP4SyUDSNUSVfM3LZXV1xh45a7vyQRv21CPgQHO66' Dec 11 22:06:36 volumio volumio[1378]: } Dec 11 22:06:36 volumio volumio[1378]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 22:06:36 volumio sudo[8722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 22:05' Dec 11 22:06:36 volumio sudo[8722]: 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:33:08 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="434a4f7d2e5f6acafabfcca26b8e5834"