-- Logs begin at Sat 2024-11-02 13:17:57 JST, end at Sat 2024-11-02 13:23:28 JST. -- Nov 02 13:22:04 volumio volumio[1214]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Nov 02 13:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Nov 02 13:22:04 volumio sudo[2412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/resolv.conf.tail Nov 02 13:22:04 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 02 13:22:04 volumio volumio[1214]: info: Discovery: Restarting Advertising due to device name change Nov 02 13:22:04 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:04 volumio sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Nov 02 13:22:04 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:04 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Nov 02 13:22:04 volumio volumio[1214]: info: Discovery: Restarting Advertising due to device name change Nov 02 13:22:05 volumio sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Nov 02 13:22:05 volumio systemd[1]: wireless.service: Killing process 1111 (wpa_supplicant) with signal SIGKILL. Nov 02 13:22:05 volumio systemd[1]: wireless.service: Killing process 1123 (dhcpcd) with signal SIGKILL. Nov 02 13:22:05 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio systemd[1]: Stopping Wireless Services... Nov 02 13:22:05 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Nov 02 13:22:05 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock unregistered. Nov 02 13:22:05 volumio sudo[2424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Nov 02 13:22:05 volumio sudo[2424]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Nov 02 13:22:05 volumio kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Nov 02 13:22:05 volumio sudo[2424]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio kernel: pps pps0: new PPS source ptp0 Nov 02 13:22:05 volumio kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Nov 02 13:22:05 volumio systemd[1]: wireless.service: Succeeded. Nov 02 13:22:05 volumio systemd[1]: Stopped Wireless Services. Nov 02 13:22:05 volumio systemd[1]: Starting Wireless Services... Nov 02 13:22:05 volumio wireless.js[2426]: Cleaning previous... Nov 02 13:22:05 volumio sudo[2442]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 02 13:22:05 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio avahi-daemon[829]: Withdrawing address record for 192.168.10.19 on wlan0. Nov 02 13:22:05 volumio avahi-daemon[829]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.19. Nov 02 13:22:05 volumio avahi-daemon[829]: Interface wlan0.IPv4 no longer relevant for mDNS. Nov 02 13:22:05 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio volumio[1214]: info: Discovery: A device disappeared from network Nov 02 13:22:05 volumio volumio[1214]: info: Discovery: Device volumio disappeared from network Nov 02 13:22:05 volumio sudo[2444]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 02 13:22:05 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio wireless.js[2426]: Stopped aP Nov 02 13:22:05 volumio sudo[2451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 02 13:22:05 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 02 13:22:05 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio sudo[2453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 02 13:22:05 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio sudo[2460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 02 13:22:05 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:05 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:05 volumio sudo[2462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 02 13:22:05 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:07 volumio ntpd[1203]: Deleting interface #3 wlan0, 192.168.10.19#123, interface stats: received=133, sent=133, dropped=0, active_time=234 secs Nov 02 13:22:07 volumio ntpd[1203]: 122.215.240.52 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 162.159.200.1 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 45.76.211.39 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 133.130.121.141 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 167.179.119.205 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 160.16.113.133 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 172.105.204.105 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 163.44.97.196 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 133.243.238.163 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 129.250.35.251 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 162.159.200.123 local addr 192.168.10.19 -> Nov 02 13:22:07 volumio ntpd[1203]: 129.250.35.250 local addr 192.168.10.19 -> Nov 02 13:22:08 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:08 volumio wireless.js[2426]: SETTING APPROPRIATE REG DOMAIN: JP Nov 02 13:22:08 volumio sudo[2473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 02 13:22:08 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:08 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:08 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Nov 02 13:22:08 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:08 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:08 volumio wireless.js[2426]: SUCCESSFULLY SET NEW REGDOMAIN: JP Nov 02 13:22:08 volumio wireless.js[2426]: Start wireless flow Nov 02 13:22:08 volumio wireless.js[2426]: Stopped hotspot (if there).. Nov 02 13:22:08 volumio sudo[2480]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 02 13:22:08 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:08 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:08 volumio sudo[2482]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 02 13:22:08 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:08 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:08 volumio wireless.js[2426]: DHCP IP Nov 02 13:22:08 volumio wireless.js[2426]: Start ap Nov 02 13:22:08 volumio wpa_supplicant[2484]: Successfully initialized wpa_supplicant Nov 02 13:22:08 volumio sudo[2485]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 02 13:22:08 volumio sudo[2485]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:08 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 02 13:22:08 volumio dhcpcd[2486]: main: control_open: Connection refused Nov 02 13:22:08 volumio dhcpcd[2486]: dev: loaded udev Nov 02 13:22:09 volumio dhcpcd-run-hooks[2517]: wlan0: starting wpa_supplicant Nov 02 13:22:09 volumio dhcpcd-run-hooks[2524]: wlan0: failed to start wpa_supplicant Nov 02 13:22:09 volumio dhcpcd-run-hooks[2525]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Nov 02 13:22:09 volumio dhcpcd[2486]: wlan0: connected to Access Point `' Nov 02 13:22:09 volumio dhcpcd[2486]: no interfaces have a carrier Nov 02 13:22:09 volumio dhcpcd[2486]: forked to background, child pid 2547 Nov 02 13:22:09 volumio dhcpcd[2547]: eth0: waiting for carrier Nov 02 13:22:09 volumio dhcpcd[2547]: wlan0: waiting for carrier Nov 02 13:22:09 volumio sudo[2485]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:09 volumio wireless.js[2426]: trying... Nov 02 13:22:09 volumio sudo[2549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:09 volumio sudo[2549]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:09 volumio sudo[2549]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:09 volumio volumio[1214]: info: Discovery: Started advertising with name: Volumio Nov 02 13:22:09 volumio volumio[1214]: info: Discovery: Started advertising with name: Volumio Nov 02 13:22:10 volumio wireless.js[2426]: trying... Nov 02 13:22:10 volumio sudo[2556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:10 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:10 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:11 volumio wpa_supplicant[2521]: wlan0: Trying to associate with SSID 'Deco@T' Nov 02 13:22:11 volumio wireless.js[2426]: trying... Nov 02 13:22:11 volumio sudo[2561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:11 volumio sudo[2561]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:11 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:12 volumio wireless.js[2426]: trying... Nov 02 13:22:12 volumio sudo[2566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:12 volumio sudo[2566]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:12 volumio sudo[2566]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:13 volumio wireless.js[2426]: trying... Nov 02 13:22:13 volumio sudo[2571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:13 volumio sudo[2571]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:13 volumio sudo[2571]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: carrier acquired Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: connected to Access Point `Deco@T' Nov 02 13:22:14 volumio wpa_supplicant[2521]: wlan0: Associated with e4:fa:c4:68:18:fe Nov 02 13:22:14 volumio wpa_supplicant[2521]: wlan0: CTRL-EVENT-CONNECTED - Connection to e4:fa:c4:68:18:fe completed [id=0 id_str=] Nov 02 13:22:14 volumio wpa_supplicant[2521]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 02 13:22:14 volumio wpa_supplicant[2521]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Nov 02 13:22:14 volumio dhcpcd[2547]: DUID 00:01:00:01:2e:75:af:3e:2c:cf:67:13:d9:6d Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: IAID 67:13:d9:6d Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: adding address fe80::2ecf:67ff:fe13:d96d Nov 02 13:22:14 volumio dhcpcd[2547]: ipv6_addaddr1: Permission denied Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: carrier lost Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: carrier acquired Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: IAID 67:13:d9:6d Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: rebinding lease of 192.168.10.19 Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: probing address 192.168.10.19/24 Nov 02 13:22:14 volumio wireless.js[2426]: trying... Nov 02 13:22:14 volumio sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:14 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:14 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:14 volumio dhcpcd[2547]: wlan0: soliciting an IPv6 router Nov 02 13:22:15 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Nov 02 13:22:15 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Nov 02 13:22:15 volumio volumio[1214]: info: Refreshing Cached IP Addresses Nov 02 13:22:15 volumio sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Nov 02 13:22:15 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:15 volumio sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 13:22:15 volumio sudo[2603]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:15 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:15 volumio volumio[1214]: error: Upnp client error: Error: This socket has been ended by the other party Nov 02 13:22:15 volumio sudo[2606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 13:22:15 volumio sudo[2606]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:15 volumio sudo[2603]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:15 volumio sudo[2606]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:15 volumio wireless.js[2426]: trying... Nov 02 13:22:15 volumio sudo[2612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:15 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:15 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:16 volumio wireless.js[2426]: trying... Nov 02 13:22:16 volumio sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:16 volumio sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:16 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:17 volumio wireless.js[2426]: trying... Nov 02 13:22:17 volumio sudo[2622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:17 volumio sudo[2622]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:17 volumio sudo[2622]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:18 volumio wireless.js[2426]: trying... Nov 02 13:22:18 volumio sudo[2627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:18 volumio sudo[2627]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:18 volumio sudo[2627]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:19 volumio dhcpcd[2547]: wlan0: leased 192.168.10.19 for 7200 seconds Nov 02 13:22:19 volumio avahi-daemon[829]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.19. Nov 02 13:22:19 volumio avahi-daemon[829]: New relevant interface wlan0.IPv4 for mDNS. Nov 02 13:22:19 volumio dhcpcd[2547]: wlan0: adding route to 192.168.10.0/24 Nov 02 13:22:19 volumio dhcpcd[2547]: wlan0: adding default route via 192.168.10.1 Nov 02 13:22:19 volumio avahi-daemon[829]: Registering new address record for 192.168.10.19 on wlan0.IPv4. Nov 02 13:22:19 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.10.19 from 192.168.10.14 UA: Mozilla/5.0 (Linux; Android 9; KFONWI Build/PS7329.3856N; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/126.0.6478.186 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 02 13:22:19 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.10.19 from 192.168.10.14 UA: Mozilla/5.0 (Linux; Android 9; KFONWI Build/PS7329.3856N; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/126.0.6478.186 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 02 13:22:19 volumio volumio[1214]: verbose: New Socket.io Connection to 192.168.10.19 from 192.168.10.14 UA: Mozilla/5.0 (Linux; Android 9; KFONWI Build/PS7329.3856N; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/126.0.6478.186 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 02 13:22:19 volumio wireless.js[2426]: trying... Nov 02 13:22:19 volumio sudo[2657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 02 13:22:19 volumio sudo[2657]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:19 volumio sudo[2657]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:19 volumio wireless.js[2426]: Connected to: ----Deco@T Nov 02 13:22:19 volumio wireless.js[2426]: ---- Nov 02 13:22:19 volumio sudo[2660]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 13:22:19 volumio sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:19 volumio sudo[2660]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:19 volumio wireless.js[2426]: ... joined AP, wlan0 IPv4 is 192.168.10.19, ipV6 is undefined Nov 02 13:22:19 volumio wireless.js[2426]: It's done! AP Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 13:22:20 volumio systemd[1]: Started Wireless Services. Nov 02 13:22:20 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 02 13:22:20 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:22:20 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 13:22:20 volumio volumio[1214]: info: Received Get System Info Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 13:22:20 volumio volumio[1214]: info: Discovery: Getting this device information Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:22:20 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:22:20 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:20 volumio volumio[1214]: info: Listing playlists Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetQueue Nov 02 13:22:20 volumio volumio[1214]: info: CoreStateMachine::getQueue Nov 02 13:22:20 volumio volumio[1214]: info: CorePlayQueue::getQueue Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 02 13:22:20 volumio volumio[1214]: info: Discovery: adding 4acc3ccb-abd1-4de5-9e42-b8ae20c969c1 Nov 02 13:22:20 volumio volumio[1214]: info: Discovery: Found device Volumio Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:22:20 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 13:22:20 volumio volumio[1214]: info: Discovery: Getting this device information Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:22:20 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 13:22:20 volumio ntpd[1203]: Listen normally on 4 wlan0 192.168.10.19:123 Nov 02 13:22:20 volumio ntpd[1203]: new interface(s) found: waking up resolver Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 13:22:20 volumio volumio[1214]: info: Discovery: Getting this device information Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:22:20 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:20 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 13:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Nov 02 13:22:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Nov 02 13:22:25 volumio sudo[2677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 13:22:25 volumio sudo[2677]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:25 volumio sudo[2677]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:25 volumio sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Nov 02 13:22:25 volumio sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:25 volumio sudo[2680]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:25 volumio sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 13:22:25 volumio sudo[2685]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:25 volumio sudo[2685]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:25 volumio sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 13:22:25 volumio sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:25 volumio sudo[2687]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:25 volumio sudo[2691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 13:22:25 volumio sudo[2691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:25 volumio sudo[2691]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:25 volumio volumio[1214]: info: Upmpdcli Daemon Started Nov 02 13:22:29 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::ClearQueue Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::stop Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:29 volumio volumio[1214]: info: CorePlayQueue::clearPlayQueue Nov 02 13:22:29 volumio volumio[1214]: info: CorePlayQueue::saveQueue Nov 02 13:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::addQueueItems Nov 02 13:22:29 volumio volumio[1214]: info: CorePlayQueue::addQueueItems Nov 02 13:22:29 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@190 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@190 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@189 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@189 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@185 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@185 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@187 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@187 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@186 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@186 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@188 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@188 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@191 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@191 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@193 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@193 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@192 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@192 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@175 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@175 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@176 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@176 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@177 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@177 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@178 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@178 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@179 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@179 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@180 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@180 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@181 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@181 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@182 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@182 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@183 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@183 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@184 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@184 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@124 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@124 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@126 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@126 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@125 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@125 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@127 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@127 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@128 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@128 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@129 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@129 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@130 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@130 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@131 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@131 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@132 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@132 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@133 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@133 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@134 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@134 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@135 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@135 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@136 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@136 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@137 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@137 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@138 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@138 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@139 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@139 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@140 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@140 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@141 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@141 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@142 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@142 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@143 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@143 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@144 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@144 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@145 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@145 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@146 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@146 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@147 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@147 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@148 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@148 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@149 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@149 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@150 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@150 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@151 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@151 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@152 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@152 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@153 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@153 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@154 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@154 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@155 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@155 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@156 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@156 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@157 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@157 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@158 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@158 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@159 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@159 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@160 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@160 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@161 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@161 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@162 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@162 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@163 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@163 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@164 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@164 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@165 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@165 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@166 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@166 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@167 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@167 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@168 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@168 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@169 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@169 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@170 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@170 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@171 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@171 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@172 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@172 Nov 02 13:22:29 volumio volumio[1214]: info: Adding Item to queue: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@173 Nov 02 13:22:29 volumio volumio[1214]: info: Using cached record of: upnp/http://192.168.10.101:50001/ContentDirectory/control@23$@173 Nov 02 13:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Nov 02 13:22:29 volumio volumio[1214]: info: CorePlayQueue::saveQueue Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::updateTrackBlock Nov 02 13:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrackBlock Nov 02 13:22:29 volumio volumio[1214]: info: CoreCommandRouter::volumioPlay Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::play index 35 Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::stop Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::play index undefined Nov 02 13:22:29 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:29 volumio volumio[1214]: info: CorePlayQueue::getTrack 35 Nov 02 13:22:30 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Nov 02 13:22:30 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Nov 02 13:22:30 volumio volumio[1214]: info: Refreshing Cached IP Addresses Nov 02 13:22:30 volumio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Nov 02 13:22:30 volumio sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:30 volumio sudo[2704]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:30 volumio sudo[2706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 13:22:30 volumio sudo[2706]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:30 volumio sudo[2706]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:30 volumio volumio[1214]: error: Upnp client error: Error: This socket has been ended by the other party Nov 02 13:22:30 volumio sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 13:22:30 volumio sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:30 volumio sudo[2710]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:40 volumio sudo[2734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 13:22:40 volumio sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:40 volumio sudo[2734]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:40 volumio sudo[2736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 13:22:40 volumio sudo[2736]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:40 volumio sudo[2736]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:40 volumio sudo[2739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 13:22:40 volumio sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:22:40 volumio sudo[2739]: pam_unix(sudo:session): session closed for user root Nov 02 13:22:40 volumio volumio[1214]: info: Upmpdcli Daemon Started Nov 02 13:22:41 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 02 13:22:41 volumio volumio[1214]: info: In handleBrowseUri, curUri=spotify Nov 02 13:22:41 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:41 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:41 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:41 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:41 volumio ntpd[1203]: Soliciting pool server 133.243.238.243 Nov 02 13:22:43 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 02 13:22:43 volumio volumio[1214]: info: In handleBrowseUri, curUri=spotify/playlists Nov 02 13:22:44 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:44 volumio ntpd[1203]: Soliciting pool server 45.77.20.103 Nov 02 13:22:45 volumio ntpd[1203]: Soliciting pool server 202.181.103.212 Nov 02 13:22:46 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 02 13:22:46 volumio volumio[1214]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:0qRUJmia0bO4liFDcQ0An4 Nov 02 13:22:46 volumio ntpd[1203]: Soliciting pool server 202.182.111.234 Nov 02 13:22:47 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6Ycsw17H90I1vhACboKI41 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7bx7Ny7L7Kwqfi1vL6n7rX Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1HIXZHC62fnBdWykcUNdY4 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3YbA61OmBgw7pwI2ysur5V Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1bYItjcPRtxZs6Ir71O5RB Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0jDijY2qjws3rbf91ObiUO Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2x0Bu5pbLHuInrUpK1jUWr Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:04s7uUtOcVhMzIglxgrE8a Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2ZbtzZXWthnc9npHblXP3V Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:5hJFER3xZVhch296X5azBr Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4q34EABWsdvbC8vUuzDSq5 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:463wvwQJ7DJCvBvbvD55xz Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:66YI4JFp7BcMcepibGprsN Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4MVvSNPOUd2z70vkkNCbLr Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4Y8ExWP86pFhUHAmpdc8UA Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0Vqe8IzOyJnScqjR4MERWh Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0UNqa99lmxyUUo57DEsYAZ Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4VVW7zufMomMHQ3gg6VljG Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4q9g66QBVulLS1C6XpggyJ Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:5mzTc5Z54XBiEuhbKHt6Oc Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6AjznBCgVaYESh8UHsHpDz Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6k3PVeDsILF9UIRokDwh7D Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3AGIus6oo2lCZRE3xF6CvJ Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3Cq4BUlxDg4ulj7XnN2COP Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2FXk3LhwfbziRcCg8Rp0fN Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7nPGoJPiUpTu2Z2YZ15LaD Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7LImTa7vwDT3dDFBzq5wfw Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0MoZADdiiC9yzcWpBbNYAC Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4DDnkTcrSKlomJaSNLEdPY Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6MluMUTdEpIljO8b80W7o1 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3Q6IrbUHFN2iyXXFfpQ8Lg Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:01hJnhpAmjzg85Etnz2ECH Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0LaR6ZaHXUTZoqqpbQlT5n Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3cCpVzzZbTCInDZGcXYOPu Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1fuwSL5ySKDfdQ0nK0acud Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2oju59yiJ8sGsjxL1p29VS Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1qgQmCZ3fUkG2dNSYI6OvN Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2LplN8w52J2HfLbJGNwfxh Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3zeTHjRg21dMPBzAUW3Vve Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1SHjeFXej7qNXpHFAuv9zL Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:5P9guvAyGzysMhzMyADHOQ Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7zC3CmcLczLxUrqZ1fOZ9s Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3tze4nATeCjWWqoWA9aQoz Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4zcvEkSouqts6NsNUnpipo Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6UxmU0jW8CXsUmltZRaVob Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:47FomdFcip5S7Yk2bRlERW Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7J8cyzumDB2CJpfAFsO1hp Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:23zwjPsg87H9KVpUfG100K Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6ZsKRsnPsdZvXfqqE6bTPy Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7dwEFJGLcO6sQJtw9AxUq4 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3TFxZHXUFvwHuFrVghjj7x Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3NxRVqbwByRGvlo6Sb1ISF Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0NTxPntOPk44Boyl985eAV Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:41lXZPD3XE7SEmAwkhcnCx Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:10CvmVT4w6IyY5Zagrswnb Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:626CWE7ZgdKZZuwOVDyqFr Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:38DCbTi6GFEmpdH1MSZVjx Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7uUyT5Ohr19KbJNYO9LIfr Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0KnitW0abCGwVMNCK3NgTL Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:5O4WT9Z7GfFN9GNVWdrNZE Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2hBJSGgK3Ag71cmwbRW7FN Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1VPTMYWngV3jTnyleu6GKh Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6aeVPdIxXTxJqQKbH49jZN Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4cYxtbTPSjdzjGmCZIostu Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3vagdREllUmBIPNDdgPkBo Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0Q3GxUzrggJLs6p8bGyj94 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1st4NBonZ4GWOFPG8aVyQz Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7sjejBA3a2Ky7TEOZOeR5w Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4X0THTF28JuCpNR7gWU7GI Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4DCqxiuqN5JbTWDh9tNEd1 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2PvP9CdrciltsJgyL3e0Oc Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:79r72Pwex9tyekxcu7rH8k Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1oK6pJXeQpLNt353hbOW91 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1YPXH66mJBpqKPpRUuzYFt Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0dSmNVMrWoGmgMKWRXxz3z Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:59IXYRypw3RVaWQY34DG3H Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2O98KWbbKopDk9ozSJC3pi Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:23bcu1jfy1PNhIGAuRTec0 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4nDeKtGWrhQO4afLKLqtQ3 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4azaCywcGWA94NckkPHCs4 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1itfbA7tYhoiZN1kgxPqrb Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0SwZTOOdq1Obg6DRA6fjtf Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:7KP3jPXSIjB2xx6UifWQ2V Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:1JREY0mLAHP3FeyzXI8Ecy Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2M5KTKmwhvl4Mdt9KfolIA Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:3cglvnRg5rQQbulRfUj6g1 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:4azaCywcGWA94NckkPHCs4 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2KPpmHSHLi7xNGeBFO2TQr Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:2fF1N3N3x1RoXSLcp39Byh Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:6lh9brKBA9qvkBWwKCMfWT Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:32axcV9zBkJKmh1cq7Yka7 Nov 02 13:22:47 volumio volumio[1214]: info: Preloading song: spotify:track:0X6I1Ze2wVESyvyRkqdMsq Nov 02 13:22:47 volumio volumio[1214]: info: Exploding uri spotify:track:6Ycsw17H90I1vhACboKI41 in service spop Nov 02 13:22:47 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:6Ycsw17H90I1vhACboKI41 Nov 02 13:22:47 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 13:22:47 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 13:22:47 volumio volumio[1214]: info: Discovery: Getting this device information Nov 02 13:22:47 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:22:47 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:47 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 13:22:47 volumio volumio[1214]: info: Exploding uri spotify:track:7bx7Ny7L7Kwqfi1vL6n7rX in service spop Nov 02 13:22:47 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:7bx7Ny7L7Kwqfi1vL6n7rX Nov 02 13:22:47 volumio volumio[1214]: info: Exploding uri spotify:track:1HIXZHC62fnBdWykcUNdY4 in service spop Nov 02 13:22:47 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:1HIXZHC62fnBdWykcUNdY4 Nov 02 13:22:47 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6Ycsw17H90I1vhACboKI41","service":"spop","name":"The Way You Look Tonight","artist":"Tony Bennett","album":"My Best Friend's Wedding: Music From The Motion Picture","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273477a41b74b1ec0c2d89d7731","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:47 volumio volumio[1214]: info: Exploding uri spotify:track:3YbA61OmBgw7pwI2ysur5V in service spop Nov 02 13:22:47 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:3YbA61OmBgw7pwI2ysur5V Nov 02 13:22:47 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7bx7Ny7L7Kwqfi1vL6n7rX","service":"spop","name":"Fascinatin' Rhythm","artist":"Tony Bennett","album":"In Person!","type":"song","duration":88,"albumart":"https://i.scdn.co/image/ab67616d0000b2737eb9762a0b865194e12376a7","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:47 volumio volumio[1214]: info: Exploding uri spotify:track:1bYItjcPRtxZs6Ir71O5RB in service spop Nov 02 13:22:47 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:1bYItjcPRtxZs6Ir71O5RB Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1HIXZHC62fnBdWykcUNdY4","service":"spop","name":"Nice Work If You Can Get It","artist":"Tony Bennett","album":"Nice Work If You Can Get It","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b273e8f8739e9af8f38ef7e22ce5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:0jDijY2qjws3rbf91ObiUO in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:0jDijY2qjws3rbf91ObiUO Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3YbA61OmBgw7pwI2ysur5V","service":"spop","name":"The Best Is Yet to Come","artist":"Tony Bennett","album":"Tony Bennett Celebrates 90","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b27337f960b83eaa1046751aeaa9","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:2x0Bu5pbLHuInrUpK1jUWr in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:2x0Bu5pbLHuInrUpK1jUWr Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1bYItjcPRtxZs6Ir71O5RB","service":"spop","name":"(I Left My Heart) In San Francisco","artist":"Tony Bennett","album":"I Left My Heart In San Francisco","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273d1631eccb03201e59054a370","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:04s7uUtOcVhMzIglxgrE8a in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:04s7uUtOcVhMzIglxgrE8a Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0jDijY2qjws3rbf91ObiUO","service":"spop","name":"Put on a Happy Face","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:2ZbtzZXWthnc9npHblXP3V in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:2ZbtzZXWthnc9npHblXP3V Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2x0Bu5pbLHuInrUpK1jUWr","service":"spop","name":"Because of You","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:5hJFER3xZVhch296X5azBr in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:5hJFER3xZVhch296X5azBr Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:04s7uUtOcVhMzIglxgrE8a","service":"spop","name":"Cold, Cold Heart","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:4q34EABWsdvbC8vUuzDSq5 in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:4q34EABWsdvbC8vUuzDSq5 Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:463wvwQJ7DJCvBvbvD55xz in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:463wvwQJ7DJCvBvbvD55xz Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ZbtzZXWthnc9npHblXP3V","service":"spop","name":"Blue Velvet","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:66YI4JFp7BcMcepibGprsN in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:66YI4JFp7BcMcepibGprsN Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4q34EABWsdvbC8vUuzDSq5","service":"spop","name":"Stranger in Paradise (with Percy Faith & His Orchestra)","artist":"Tony Bennett","album":"60 Years: The Artistry of Tony Bennett","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273838907b56e8015b7bf9ab2cf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5hJFER3xZVhch296X5azBr","service":"spop","name":"Rags to Riches (with Percy Faith & His Orchestra)","artist":"Tony Bennett","album":"60 Years: The Artistry of Tony Bennett","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b273838907b56e8015b7bf9ab2cf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:4MVvSNPOUd2z70vkkNCbLr in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:4MVvSNPOUd2z70vkkNCbLr Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:463wvwQJ7DJCvBvbvD55xz","service":"spop","name":"The Best Is Yet to Come","artist":"Tony Bennett","album":"I Left My Heart In San Francisco","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b273d1631eccb03201e59054a370","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:4Y8ExWP86pFhUHAmpdc8UA in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:4Y8ExWP86pFhUHAmpdc8UA Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:66YI4JFp7BcMcepibGprsN","service":"spop","name":"The Shadow of Your Smile (Love Theme from \"The Sandpiper\")","artist":"Tony Bennett","album":"The Movie Song Album","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b2735feaee09144f20f365f04018","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:0Vqe8IzOyJnScqjR4MERWh in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:0Vqe8IzOyJnScqjR4MERWh Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:0UNqa99lmxyUUo57DEsYAZ in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:0UNqa99lmxyUUo57DEsYAZ Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Y8ExWP86pFhUHAmpdc8UA","service":"spop","name":"The Boulevard of Broken Dreams","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:4VVW7zufMomMHQ3gg6VljG in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:4VVW7zufMomMHQ3gg6VljG Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4MVvSNPOUd2z70vkkNCbLr","service":"spop","name":"The Good Life","artist":"Tony Bennett","album":"I Wanna Be Around","type":"song","duration":134,"albumart":"https://i.scdn.co/image/ab67616d0000b273eefffb2d5a482f7b5b6c7318","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:4q9g66QBVulLS1C6XpggyJ in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:4q9g66QBVulLS1C6XpggyJ Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Vqe8IzOyJnScqjR4MERWh","service":"spop","name":"It Amazes Me","artist":"Tony Bennett","album":"60 Years: The Artistry of Tony Bennett","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b273838907b56e8015b7bf9ab2cf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:5mzTc5Z54XBiEuhbKHt6Oc in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:5mzTc5Z54XBiEuhbKHt6Oc Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0UNqa99lmxyUUo57DEsYAZ","service":"spop","name":"I Wanna Be Around","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4VVW7zufMomMHQ3gg6VljG","service":"spop","name":"Smile","artist":"Tony Bennett","album":"The Movie Song Album","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b2735feaee09144f20f365f04018","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:6AjznBCgVaYESh8UHsHpDz in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:6AjznBCgVaYESh8UHsHpDz Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4q9g66QBVulLS1C6XpggyJ","service":"spop","name":"Young And Foolish","artist":"Tony Bennett","album":"The Tony Bennett / Bill Evans Album (Expanded Edition)","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b2739a89f156fb89a3d9fbd9cacf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:6k3PVeDsILF9UIRokDwh7D in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:6k3PVeDsILF9UIRokDwh7D Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5mzTc5Z54XBiEuhbKHt6Oc","service":"spop","name":"In The Middle Of An Island","artist":"Tony Bennett","album":"The Columbia Singles, Vol. 5","type":"song","duration":126,"albumart":"https://i.scdn.co/image/ab67616d0000b2730c1a3600ee28b7dadf2dd6ff","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:3AGIus6oo2lCZRE3xF6CvJ in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:3AGIus6oo2lCZRE3xF6CvJ Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6AjznBCgVaYESh8UHsHpDz","service":"spop","name":"Fly Me To The Moon","artist":"Tony Bennett","album":"If I Ruled The World: Songs For The Jet Set","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b2732bc7cd39261dc386ebfb9792","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:3Cq4BUlxDg4ulj7XnN2COP in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:3Cq4BUlxDg4ulj7XnN2COP Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6k3PVeDsILF9UIRokDwh7D","service":"spop","name":"The Very Thought Of You","artist":"Tony Bennett","album":"A Time For Love","type":"song","duration":276,"albumart":"https://i.scdn.co/image/ab67616d0000b27345d8d5fd1fa5378ebd7e40b5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:48 volumio volumio[1214]: info: Exploding uri spotify:track:2FXk3LhwfbziRcCg8Rp0fN in service spop Nov 02 13:22:48 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:2FXk3LhwfbziRcCg8Rp0fN Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3AGIus6oo2lCZRE3xF6CvJ","service":"spop","name":"Just in Time","artist":"Tony Bennett","album":"60 Years: The Artistry of Tony Bennett","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273838907b56e8015b7bf9ab2cf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:7nPGoJPiUpTu2Z2YZ15LaD in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:7nPGoJPiUpTu2Z2YZ15LaD Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:7LImTa7vwDT3dDFBzq5wfw in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:7LImTa7vwDT3dDFBzq5wfw Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Cq4BUlxDg4ulj7XnN2COP","service":"spop","name":"Anything Goes","artist":"Tony Bennett","album":"Cheek To Cheek (Deluxe)","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b2734883efb117dd51c4f3f88e93","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2FXk3LhwfbziRcCg8Rp0fN","service":"spop","name":"Who Can I Turn To (When Nobody Needs Me)","artist":"Tony Bennett","album":"Who Can I Turn To","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273bb37d234352872ebe0021a57","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:0MoZADdiiC9yzcWpBbNYAC in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:0MoZADdiiC9yzcWpBbNYAC Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7nPGoJPiUpTu2Z2YZ15LaD","service":"spop","name":"Steppin' out with My Baby","artist":"Tony Bennett","album":"Steppin' Out","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b273ef72006a797ce391d90ab65a","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:4DDnkTcrSKlomJaSNLEdPY in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:4DDnkTcrSKlomJaSNLEdPY Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7LImTa7vwDT3dDFBzq5wfw","service":"spop","name":"If I Ruled the World","artist":"Tony Bennett","album":"If I Ruled The World: Songs For The Jet Set","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b2732bc7cd39261dc386ebfb9792","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:6MluMUTdEpIljO8b80W7o1 in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:6MluMUTdEpIljO8b80W7o1 Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0MoZADdiiC9yzcWpBbNYAC","service":"spop","name":"My Favourite Things","artist":"Tony Bennett","album":"Snowfall - The Tony Bennett Christmas Album","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273835d52eb61628600e466ead9","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:3Q6IrbUHFN2iyXXFfpQ8Lg in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:3Q6IrbUHFN2iyXXFfpQ8Lg Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4DDnkTcrSKlomJaSNLEdPY","service":"spop","name":"Firefly (with Count Basie & His Orchestra)","artist":"Tony Bennett","album":"60 Years: The Artistry of Tony Bennett","type":"song","duration":97,"albumart":"https://i.scdn.co/image/ab67616d0000b273838907b56e8015b7bf9ab2cf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:01hJnhpAmjzg85Etnz2ECH in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:01hJnhpAmjzg85Etnz2ECH Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6MluMUTdEpIljO8b80W7o1","service":"spop","name":"Once Upon a Time","artist":"Tony Bennett","album":"I Left My Heart In San Francisco","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273d1631eccb03201e59054a370","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:0LaR6ZaHXUTZoqqpbQlT5n in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:0LaR6ZaHXUTZoqqpbQlT5n Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Q6IrbUHFN2iyXXFfpQ8Lg","service":"spop","name":"How Do You Keep the Music Playing (with The Ralph Sharon Trio)","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:3cCpVzzZbTCInDZGcXYOPu in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:3cCpVzzZbTCInDZGcXYOPu Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:01hJnhpAmjzg85Etnz2ECH","service":"spop","name":"Body and Soul","artist":"Tony Bennett","album":"Duets II","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b273796c0744b04db30174704d24","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:1fuwSL5ySKDfdQ0nK0acud in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:1fuwSL5ySKDfdQ0nK0acud Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0LaR6ZaHXUTZoqqpbQlT5n","service":"spop","name":"When Do the Bells Ring for Me","artist":"Tony Bennett","album":"The Classics (Deluxe Edition)","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273b50fbd4f25570b7979800b56","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:2oju59yiJ8sGsjxL1p29VS in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:2oju59yiJ8sGsjxL1p29VS Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3cCpVzzZbTCInDZGcXYOPu","service":"spop","name":"Night and Day","artist":"Tony Bennett","album":"Sings The American Songbook, Vols. 1 - 4","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7a848b319b91c14e8c312bf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:1qgQmCZ3fUkG2dNSYI6OvN in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:1qgQmCZ3fUkG2dNSYI6OvN Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1fuwSL5ySKDfdQ0nK0acud","service":"spop","name":"Sing You Sinners","artist":"Tony Bennett","album":"60 Years: The Artistry of Tony Bennett","type":"song","duration":134,"albumart":"https://i.scdn.co/image/ab67616d0000b273838907b56e8015b7bf9ab2cf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:2LplN8w52J2HfLbJGNwfxh in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:2LplN8w52J2HfLbJGNwfxh Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:3zeTHjRg21dMPBzAUW3Vve in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:3zeTHjRg21dMPBzAUW3Vve Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2oju59yiJ8sGsjxL1p29VS","service":"spop","name":"Mood Indigo","artist":"Tony Bennett","album":"Bennett Sings Ellington / Hot And Cool","type":"song","duration":273,"albumart":"https://i.scdn.co/image/ab67616d0000b273685bcf4b5690416ce4a84138","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1qgQmCZ3fUkG2dNSYI6OvN","service":"spop","name":"Last Night When We Were Young","artist":"Tony Bennett","album":"Perfectly Frank","type":"song","duration":145,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0692fabc08c73b7e331a72d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:1SHjeFXej7qNXpHFAuv9zL in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:1SHjeFXej7qNXpHFAuv9zL Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LplN8w52J2HfLbJGNwfxh","service":"spop","name":"Where Do I Begin (Love Story)","artist":"Tony Bennett","album":"Sunrise, Sunset","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b273511891875005472dd76a7f73","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:5P9guvAyGzysMhzMyADHOQ in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:5P9guvAyGzysMhzMyADHOQ Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3zeTHjRg21dMPBzAUW3Vve","service":"spop","name":"Climb Ev'ry Mountain","artist":"Tony Bennett","album":"Sunrise, Sunset","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b273511891875005472dd76a7f73","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:track:7zC3CmcLczLxUrqZ1fOZ9s in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:track:7zC3CmcLczLxUrqZ1fOZ9s Nov 02 13:22:49 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:49 volumio volumio[1214]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 13:22:49 volumio volumio[1214]: info: CoreStateMachine::ClearQueue Nov 02 13:22:49 volumio volumio[1214]: info: CoreStateMachine::stop Nov 02 13:22:49 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:49 volumio volumio[1214]: info: CorePlayQueue::clearPlayQueue Nov 02 13:22:49 volumio volumio[1214]: info: CorePlayQueue::saveQueue Nov 02 13:22:49 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Nov 02 13:22:49 volumio volumio[1214]: info: CoreStateMachine::addQueueItems Nov 02 13:22:49 volumio volumio[1214]: info: CorePlayQueue::addQueueItems Nov 02 13:22:49 volumio volumio[1214]: info: Preload queue cleared Nov 02 13:22:49 volumio volumio[1214]: info: Adding Item to queue: spotify:user:spotify:playlist:0qRUJmia0bO4liFDcQ0An4 Nov 02 13:22:49 volumio volumio[1214]: info: Exploding uri spotify:user:spotify:playlist:0qRUJmia0bO4liFDcQ0An4 in service spop Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:0qRUJmia0bO4liFDcQ0An4 Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1SHjeFXej7qNXpHFAuv9zL","service":"spop","name":"For Once In My Life","artist":"Tony Bennett","album":"For Once In My Life","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b2731e7f3bc63b7fbe3a7317114b","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5P9guvAyGzysMhzMyADHOQ","service":"spop","name":"This Is All I Ask","artist":"Tony Bennett","album":"This Is All I Ask","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b273880fe9bbe8029166feaa4a3c","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:49 volumio volumio[1214]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7zC3CmcLczLxUrqZ1fOZ9s","service":"spop","name":"When Joanna Loved Me","artist":"Tony Bennett","album":"60 Years: The Artistry of Tony Bennett","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273838907b56e8015b7bf9ab2cf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 02 13:22:50 volumio ntpd[1203]: Soliciting pool server 2400:8500:1302:800:133:130:121:141 Nov 02 13:22:50 volumio volumio[1214]: info: CoreCommandRouter::volumioPushQueue Nov 02 13:22:50 volumio volumio[1214]: info: CorePlayQueue::saveQueue Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::updateTrackBlock Nov 02 13:22:50 volumio volumio[1214]: info: CorePlayQueue::getTrackBlock Nov 02 13:22:50 volumio volumio[1214]: info: CoreCommandRouter::volumioPlay Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::play index 0 Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::stop Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::play index undefined Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 13:22:50 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:50 volumio volumio[1214]: info: CoreStateMachine::startPlaybackTimer Nov 02 13:22:50 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:50 volumio volumio[1214]: info: [1730521370627] ControllerSpotify::clearAddPlayTrack Nov 02 13:22:50 volumio volumio[1214]: info: Sending Spotify command with payload to local API: /player/play Nov 02 13:22:50 volumio go-librespot[1391]: time="2024-11-02T13:22:50+09:00" level=debug msg="resolved context of track" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:50 volumio go-librespot[1391]: time="2024-11-02T13:22:50+09:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:50 volumio go-librespot[1391]: time="2024-11-02T13:22:50+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:50 volumio go-librespot[1391]: time="2024-11-02T13:22:50+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 02 13:22:50 volumio go-librespot[1391]: time="2024-11-02T13:22:50+09:00" level=trace msg="emitting websocket event: will_play" Nov 02 13:22:50 volumio volumio[1214]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6Ycsw17H90I1vhACboKI41","play_origin":"go-librespot"}} Nov 02 13:22:50 volumio go-librespot[1391]: time="2024-11-02T13:22:50+09:00" level=debug msg="selected format OGG_VORBIS_160 (559d57ff9757f53f87dbf7072b8d7228732b4cf8)" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:50 volumio go-librespot[1391]: time="2024-11-02T13:22:50+09:00" level=debug msg="requested aes key for file 559d57ff9757f53f87dbf7072b8d7228732b4cf8, gid: 6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="fetched first chunk of 7, total size is 3382052 bytes" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=trace msg="seek to 0ms (diff: -175ms, samples: 0, bytes: 0)" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="created new output device" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=info msg="loaded track \"The Way You Look Tonight\" (paused: false, position: 0ms, duration: 203240ms, prefetched: false)" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="fetched chunk 1/6, size: 524288" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="fetched chunk 3/6, size: 524288" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="fetched chunk 2/6, size: 524288" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=trace msg="scheduling prefetch in 173s" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=trace msg="emitting websocket event: metadata" Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6Ycsw17H90I1vhACboKI41","name":"The Way You Look Tonight","artist_names":["Tony Bennett"],"album_name":"My Best Friend's Wedding: Music From The Motion Picture","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02477a41b74b1ec0c2d89d7731","position":0,"duration":203240,"release_date":"year:1997 month:6 day:17","track_number":7,"disc_number":1}} Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 02 13:22:51 volumio go-librespot[1391]: time="2024-11-02T13:22:51+09:00" level=trace msg="emitting websocket event: playing" Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6Ycsw17H90I1vhACboKI41","play_origin":"go-librespot"}} Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: PUSH STATE SPOTIFY Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: {"status":"play","service":"spop","title":"The Way You Look Tonight","artist":"Tony Bennett","album":"My Best Friend's Wedding: Music From The Motion Picture","albumart":"https://i.scdn.co/image/ab67616d00001e02477a41b74b1ec0c2d89d7731","uri":"spotify:track:6Ycsw17H90I1vhACboKI41","trackType":"spotify","seek":0,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 02 13:22:51 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Nov 02 13:22:51 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:51 volumio volumio[1214]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Way You Look Tonight","artist":"Tony Bennett","album":"My Best Friend's Wedding: Music From The Motion Picture","albumart":"https://i.scdn.co/image/ab67616d00001e02477a41b74b1ec0c2d89d7731","uri":"spotify:track:6Ycsw17H90I1vhACboKI41","trackType":"spotify","seek":0,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 02 13:22:51 volumio volumio[1214]: verbose: CURRENT POSITION 0 Nov 02 13:22:51 volumio volumio[1214]: info: CoreStateMachine::syncState stateService play Nov 02 13:22:51 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: PUSH STATE SPOTIFY Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: {"status":"play","service":"spop","title":"The Way You Look Tonight","artist":"Tony Bennett","album":"My Best Friend's Wedding: Music From The Motion Picture","albumart":"https://i.scdn.co/image/ab67616d00001e02477a41b74b1ec0c2d89d7731","uri":"spotify:track:6Ycsw17H90I1vhACboKI41","trackType":"spotify","seek":0,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 02 13:22:51 volumio volumio[1214]: info: CoreCommandRouter::servicePushState Nov 02 13:22:51 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:51 volumio volumio[1214]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"The Way You Look Tonight","artist":"Tony Bennett","album":"My Best Friend's Wedding: Music From The Motion Picture","albumart":"https://i.scdn.co/image/ab67616d00001e02477a41b74b1ec0c2d89d7731","uri":"spotify:track:6Ycsw17H90I1vhACboKI41","trackType":"spotify","seek":0,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 02 13:22:51 volumio volumio[1214]: verbose: CURRENT POSITION 0 Nov 02 13:22:51 volumio volumio[1214]: info: CoreStateMachine::syncState stateService play Nov 02 13:22:51 volumio volumio[1214]: info: CoreStateMachine::syncState currentStatus play Nov 02 13:22:51 volumio volumio[1214]: info: Received an update from plugin. extracting info from payload Nov 02 13:22:51 volumio volumio[1214]: info: CoreStateMachine::pushState Nov 02 13:22:51 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:51 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 13:22:51 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Nov 02 13:22:51 volumio volumio[1214]: info: CoreStateMachine::pushState Nov 02 13:22:51 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:22:51 volumio volumio[1214]: info: CoreCommandRouter::volumioPushState Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 13:22:51 volumio volumio[1214]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 13:23:12 volumio volumio[1214]: info: Retrieving Cloud Streaming UI Nov 02 13:23:12 volumio volumio[1214]: info: Getting Tidal Cloud Configuration Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 13:23:12 volumio volumio[1214]: info: Getting Qobuz Cloud Configuration Nov 02 13:23:12 volumio volumio[1214]: info: Asking plugin for UI Config Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 13:23:12 volumio volumio[1214]: info: Getting Spotify Cloud Configuration Nov 02 13:23:12 volumio volumio[1214]: info: Asking plugin for UI Config Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 13:23:12 volumio volumio[1214]: info: Saving Spotify Acccount Nov 02 13:23:12 volumio volumio[1214]: info: Got Tidal Cloud Configuration Nov 02 13:23:12 volumio volumio[1214]: info: Got it Nov 02 13:23:12 volumio volumio[1214]: info: Got it Nov 02 13:23:12 volumio volumio[1214]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 13:23:12 volumio volumio[1214]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 13:23:12 volumio volumio[1214]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 13:23:13 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 13:23:13 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 13:23:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 13:23:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 13:23:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 13:23:17 volumio volumio[1214]: info: Discovery: Getting this device information Nov 02 13:23:17 volumio volumio[1214]: info: CoreCommandRouter::volumioGetState Nov 02 13:23:17 volumio volumio[1214]: info: CorePlayQueue::getTrack 0 Nov 02 13:23:17 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 13:23:21 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 13:23:21 volumio go-librespot[1391]: time="2024-11-02T13:23:21+09:00" level=debug msg="fetched chunk 4/6, size: 524288" uri="spotify:track:6Ycsw17H90I1vhACboKI41" Nov 02 13:23:22 volumio volumio[1214]: info: Disabling MyMusic plugin upnp Nov 02 13:23:22 volumio sudo[2869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 02 13:23:22 volumio sudo[2869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 13:23:22 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 02 13:23:22 volumio volumio[1214]: error: Upnp client error: Error: This socket has been ended by the other party Nov 02 13:23:25 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 13:23:28 volumio volumio[1214]: info: Enabling MyMusic plugin upnp Nov 02 13:23:28 volumio volumio[1214]: info: Enabling plugin upnp Nov 02 13:23:28 volumio volumio[1214]: info: Loading plugin "upnp"... Nov 02 13:23:28 volumio volumio[1214]: info: [1730521408426] Starting Upmpd Daemon Nov 02 13:23:28 volumio volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 13:23:28 volumio volumio[1214]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 13:23:28 volumio volumio[1214]: Error: listen EADDRINUSE: address already in use :::6599 Nov 02 13:23:28 volumio volumio[1214]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 02 13:23:28 volumio volumio[1214]: at listenInCluster (net.js:1366:12) Nov 02 13:23:28 volumio volumio[1214]: at Server.listen (net.js:1452:7) Nov 02 13:23:28 volumio volumio[1214]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 02 13:23:28 volumio volumio[1214]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 02 13:23:28 volumio volumio[1214]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 02 13:23:28 volumio volumio[1214]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 02 13:23:28 volumio volumio[1214]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 02 13:23:28 volumio volumio[1214]: code: 'EADDRINUSE', Nov 02 13:23:28 volumio volumio[1214]: errno: -98, Nov 02 13:23:28 volumio volumio[1214]: syscall: 'listen', Nov 02 13:23:28 volumio volumio[1214]: address: '::', Nov 02 13:23:28 volumio volumio[1214]: port: 6599 Nov 02 13:23:28 volumio volumio[1214]: } Nov 02 13:23:28 volumio volumio[1214]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 13:23:28 volumio sudo[2893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 13:22 Nov 02 13:23:28 volumio sudo[2893]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"