-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Thu 2024-11-28 17:53:36 CET. -- Nov 28 17:52:00 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:00 oradiokeuken sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:00 oradiokeuken sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:00 oradiokeuken sudo[871]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:01 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:01 oradiokeuken sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:01 oradiokeuken sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:01 oradiokeuken sudo[874]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:01 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:01] [info] asio async_connect error: system:111 (Connection refused) Nov 28 17:52:01 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 28 17:52:01 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:01] [error] handle_connect error: Underlying Transport Error Nov 28 17:52:02 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:02 oradiokeuken sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:02 oradiokeuken sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:02 oradiokeuken sudo[877]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:03 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:03 oradiokeuken sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:03 oradiokeuken sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:03 oradiokeuken sudo[880]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:03 oradiokeuken wpa_supplicant[842]: wlan0: Trying to associate with SSID 'mastkraan' Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: carrier acquired Nov 28 17:52:03 oradiokeuken wpa_supplicant[842]: wlan0: Associated with 60:a4:b7:63:eb:22 Nov 28 17:52:03 oradiokeuken wpa_supplicant[842]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:a4:b7:63:eb:22 completed [id=0 id_str=] Nov 28 17:52:03 oradiokeuken wpa_supplicant[842]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 28 17:52:03 oradiokeuken wpa_supplicant[842]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Nov 28 17:52:03 oradiokeuken dhcpcd[858]: DUID 00:01:00:01:2e:c1:0e:30:b8:27:eb:88:10:b4 Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: IAID eb:4a:06:77 Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: adding address fe80::b872:4953:43e4:d936 Nov 28 17:52:03 oradiokeuken dhcpcd[858]: ipv6_addaddr1: Permission denied Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: carrier lost Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: carrier acquired Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: connected to Access Point `mastkraan' Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: IAID eb:4a:06:77 Nov 28 17:52:03 oradiokeuken dhcpcd[858]: wlan0: soliciting an IPv6 router Nov 28 17:52:04 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:04 oradiokeuken sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:04 oradiokeuken sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:04 oradiokeuken sudo[896]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:04 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Fetching time from Volumio... Nov 28 17:52:04 oradiokeuken dhcpcd[858]: wlan0: rebinding lease of 192.168.1.115 Nov 28 17:52:04 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Date not found in response Nov 28 17:52:04 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Retrying in 5 seconds... Nov 28 17:52:04 oradiokeuken dhcpcd[858]: wlan0: probing address 192.168.1.115/24 Nov 28 17:52:05 oradiokeuken wpa_supplicant[842]: RRM: Ignoring radio measurement request: Not RRM network Nov 28 17:52:05 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:05 oradiokeuken sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:05 oradiokeuken sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:05 oradiokeuken sudo[906]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:05 oradiokeuken dhcpcd[858]: wlan0: carrier lost Nov 28 17:52:05 oradiokeuken wpa_supplicant[842]: wlan0: Associated with 60:a4:b7:63:eb:23 Nov 28 17:52:05 oradiokeuken wpa_supplicant[842]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:a4:b7:63:eb:23 completed [id=0 id_str=] Nov 28 17:52:05 oradiokeuken wpa_supplicant[842]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 28 17:52:05 oradiokeuken dhcpcd[858]: wlan0: carrier acquired Nov 28 17:52:05 oradiokeuken dhcpcd[858]: wlan0: IAID eb:4a:06:77 Nov 28 17:52:05 oradiokeuken dhcpcd[858]: wlan0: rebinding lease of 192.168.1.115 Nov 28 17:52:05 oradiokeuken dhcpcd[858]: wlan0: probing address 192.168.1.115/24 Nov 28 17:52:06 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:06 oradiokeuken sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:06 oradiokeuken sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:06 oradiokeuken dhcpcd[858]: wlan0: soliciting an IPv6 router Nov 28 17:52:06 oradiokeuken sudo[930]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:07 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:07 oradiokeuken sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:07 oradiokeuken sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:07 oradiokeuken sudo[933]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:08 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:08 oradiokeuken sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:08 oradiokeuken sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:08 oradiokeuken sudo[936]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:09 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:09 oradiokeuken sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:09 oradiokeuken sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:09 oradiokeuken sudo[939]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:09 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Fetching time from Volumio... Nov 28 17:52:09 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Date not found in response Nov 28 17:52:09 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Retrying in 5 seconds... Nov 28 17:52:10 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:10 oradiokeuken sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:10 oradiokeuken sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:10 oradiokeuken sudo[947]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:10 oradiokeuken dhcpcd[858]: wlan0: leased 192.168.1.115 for 86400 seconds Nov 28 17:52:10 oradiokeuken avahi-daemon[565]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.115. Nov 28 17:52:10 oradiokeuken avahi-daemon[565]: New relevant interface wlan0.IPv4 for mDNS. Nov 28 17:52:10 oradiokeuken dhcpcd[858]: wlan0: adding route to 192.168.1.0/24 Nov 28 17:52:10 oradiokeuken avahi-daemon[565]: Registering new address record for 192.168.1.115 on wlan0.IPv4. Nov 28 17:52:10 oradiokeuken dhcpcd[858]: wlan0: adding default route via 192.168.1.254 Nov 28 17:52:11 oradiokeuken wireless.js[601]: trying... Nov 28 17:52:11 oradiokeuken sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 28 17:52:11 oradiokeuken sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:11 oradiokeuken sudo[971]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:11 oradiokeuken wireless.js[601]: Connected to: ----mastkraan Nov 28 17:52:11 oradiokeuken wireless.js[601]: ---- Nov 28 17:52:11 oradiokeuken sudo[974]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 17:52:11 oradiokeuken sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:11 oradiokeuken sudo[974]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:11 oradiokeuken wireless.js[601]: ... joined AP, wlan0 IPv4 is 192.168.1.115, ipV6 is undefined Nov 28 17:52:11 oradiokeuken wireless.js[601]: It's done! AP Nov 28 17:52:11 oradiokeuken systemd[1]: Started Wireless Services. Nov 28 17:52:11 oradiokeuken systemd[1]: Started Volumio Backend Module. Nov 28 17:52:11 oradiokeuken kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 28 17:52:11 oradiokeuken systemd[1]: Started Volumio Cpu Tweaker. Nov 28 17:52:11 oradiokeuken volumio-cpu-tweak[980]: Setting RT Priority for mpd Nov 28 17:52:11 oradiokeuken volumio-cpu-tweak[980]: Setting MPD Affinity Nov 28 17:52:11 oradiokeuken volumio-cpu-tweak[980]: pid 740's current affinity mask: f Nov 28 17:52:11 oradiokeuken volumio-cpu-tweak[980]: pid 740's new affinity mask: 3 Nov 28 17:52:11 oradiokeuken volumio-cpu-tweak[980]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 28 17:52:11 oradiokeuken volumio-cpu-tweak[980]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 28 17:52:11 oradiokeuken systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 28 17:52:11 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:11] [info] asio async_connect error: system:111 (Connection refused) Nov 28 17:52:11 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 28 17:52:11 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:11] [error] handle_connect error: Underlying Transport Error Nov 28 17:52:12 oradiokeuken nmbd[712]: [2024/11/28 17:52:12.330056, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 17:52:12 oradiokeuken systemd[1]: Started Samba NMB Daemon. Nov 28 17:52:12 oradiokeuken nmbd[712]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 28 17:52:12 oradiokeuken systemd[1]: Starting Samba Winbind Daemon... Nov 28 17:52:12 oradiokeuken ntpd[733]: Listen normally on 3 wlan0 192.168.1.115:123 Nov 28 17:52:12 oradiokeuken ntpd[733]: new interface(s) found: waking up resolver Nov 28 17:52:12 oradiokeuken winbindd[996]: [2024/11/28 17:52:12.702279, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 28 17:52:12 oradiokeuken winbindd[996]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 28 17:52:12 oradiokeuken winbindd[996]: [2024/11/28 17:52:12.727230, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 17:52:12 oradiokeuken winbindd[996]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 28 17:52:12 oradiokeuken systemd[1]: Started Samba Winbind Daemon. Nov 28 17:52:12 oradiokeuken systemd[1]: Starting Samba SMB Daemon... Nov 28 17:52:13 oradiokeuken smbd[1001]: [2024/11/28 17:52:13.304374, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 28 17:52:13 oradiokeuken smbd[1001]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 28 17:52:13 oradiokeuken systemd[1]: Started Samba SMB Daemon. Nov 28 17:52:14 oradiokeuken volumio[979]: info: ------------------------------------------- Nov 28 17:52:14 oradiokeuken volumio[979]: info: ----- Volumio3 ---- Nov 28 17:52:14 oradiokeuken volumio[979]: info: ------------------------------------------- Nov 28 17:52:14 oradiokeuken volumio[979]: info: ----- System startup ---- Nov 28 17:52:14 oradiokeuken volumio[979]: info: ------------------------------------------- Nov 28 17:52:14 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Fetching time from Volumio... Nov 28 17:52:14 oradiokeuken volumio-time-update[589]: volumio-time-update-util: Setting system time to: 2024-11-28 17:52:40 Nov 28 17:52:14 oradiokeuken sudo[1022]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-11-28 17:52:40 Nov 28 17:52:14 oradiokeuken sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:14 oradiokeuken dbus-daemon[548]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1023 comm="timedatectl set-time 2024-11-28 17:52:40 ") Nov 28 17:52:14 oradiokeuken systemd[1]: Starting Time & Date Service... Nov 28 17:52:14 oradiokeuken systemd[1]: systemd-fsckd.service: Succeeded. Nov 28 17:52:15 oradiokeuken dbus-daemon[548]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 28 17:52:15 oradiokeuken systemd[1]: Started Time & Date Service. Nov 28 17:52:40 oradiokeuken systemd-timedated[1024]: Changed local time to Thu Nov 28 17:52:40 2024 Nov 28 17:52:40 oradiokeuken sudo[1022]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:40 oradiokeuken volumio-time-update[589]: volumio-time-update-util: System time updated successfully. Nov 28 17:52:40 oradiokeuken systemd[1]: Started Volumio Time Update Utility. Nov 28 17:52:40 oradiokeuken systemd[1]: Reached target Multi-User System. Nov 28 17:52:40 oradiokeuken systemd[1]: Reached target Graphical Interface. Nov 28 17:52:40 oradiokeuken systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 28 17:52:40 oradiokeuken systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 28 17:52:40 oradiokeuken systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 28 17:52:40 oradiokeuken systemd[1]: Startup finished in 16.016s (kernel) + 35.498s (userspace) = 51.514s. Nov 28 17:52:40 oradiokeuken volumio[979]: info: MYVOLUMIO Environment detected Nov 28 17:52:40 oradiokeuken volumio[979]: info: Plugin folders cleanup Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning into folder /volumio/app/plugins/ Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category audio_interface Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category miscellanea Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category music_service Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category plugins.json Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category system_controller Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category user_interface Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning into folder /data/plugins/ Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category music_service Nov 28 17:52:40 oradiokeuken volumio[979]: info: Scanning category user_interface Nov 28 17:52:40 oradiokeuken volumio[979]: info: Plugin folders cleanup completed Nov 28 17:52:40 oradiokeuken volumio[979]: info: ------------------------------------------- Nov 28 17:52:40 oradiokeuken volumio[979]: info: ----- Core plugins startup ---- Nov 28 17:52:40 oradiokeuken volumio[979]: info: ------------------------------------------- Nov 28 17:52:40 oradiokeuken volumio[979]: info: Loading plugins from folder /volumio/app/plugins/ Nov 28 17:52:40 oradiokeuken volumio[979]: info: Adding plugin upnp to MyMusic Plugins Nov 28 17:52:40 oradiokeuken volumio[979]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 28 17:52:40 oradiokeuken volumio[979]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 28 17:52:40 oradiokeuken volumio[979]: info: Loading plugins from folder /data/plugins/ Nov 28 17:52:40 oradiokeuken volumio[979]: info: Loading plugin "system"... Nov 28 17:52:40 oradiokeuken volumio[979]: info: Loading plugin "appearance"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "network"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: Refreshing Cached IP Addresses Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "services"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "alsa_controller"... Nov 28 17:52:42 oradiokeuken sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 17:52:42 oradiokeuken sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:42 oradiokeuken sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 17:52:42 oradiokeuken sudo[1037]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:42 oradiokeuken sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:42 oradiokeuken sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 28 17:52:42 oradiokeuken sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:42 oradiokeuken sudo[1035]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:42 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "wizard"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "networkfs"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: Starting Udev Watcher for removable devices Nov 28 17:52:42 oradiokeuken volumio[979]: info: Ignoring mount for partition: boot Nov 28 17:52:42 oradiokeuken volumio[979]: info: Ignoring mount for partition: volumio Nov 28 17:52:42 oradiokeuken volumio[979]: info: Ignoring mount for partition: volumio_data Nov 28 17:52:42 oradiokeuken volumio[979]: info: Mounting Device EC4D-F75F Nov 28 17:52:42 oradiokeuken sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EC4D-F75F -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 28 17:52:42 oradiokeuken sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:42 oradiokeuken kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Nov 28 17:52:42 oradiokeuken sudo[1068]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:42 oradiokeuken kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Nov 28 17:52:42 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "volumio_command_line_client"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "upnp"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: [1732812762651] Starting Upmpd Daemon Nov 28 17:52:42 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "my_music"... Nov 28 17:52:42 oradiokeuken volumio[979]: info: Loading plugin "mpd"... Nov 28 17:52:43 oradiokeuken volumio[979]: info: Loading plugin "upnp_browser"... Nov 28 17:52:43 oradiokeuken volumio[979]: info: Loading plugin "alarm-clock"... Nov 28 17:52:43 oradiokeuken volumio[979]: info: Loading plugin "airplay_emulation"... Nov 28 17:52:43 oradiokeuken volumio[979]: info: Starting Shairport Sync Nov 28 17:52:43 oradiokeuken volumio[979]: info: Loading plugin "last_100"... Nov 28 17:52:43 oradiokeuken volumio[979]: info: Loading plugin "webradio"... Nov 28 17:52:43 oradiokeuken volumio[979]: info: Loading plugin "i2s_dacs"... Nov 28 17:52:43 oradiokeuken volumio[979]: info: Loading plugin "volumiodiscovery"... Nov 28 17:52:44 oradiokeuken volumio[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 17:52:44 oradiokeuken volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:52:44 oradiokeuken volumio[979]: *** WARNING *** For more information see Nov 28 17:52:44 oradiokeuken volumio[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 17:52:44 oradiokeuken node[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 28 17:52:44 oradiokeuken volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:52:44 oradiokeuken volumio[979]: *** WARNING *** For more information see Nov 28 17:52:44 oradiokeuken node[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:52:44 oradiokeuken node[979]: *** WARNING *** For more information see Nov 28 17:52:44 oradiokeuken node[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 28 17:52:44 oradiokeuken node[979]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 28 17:52:44 oradiokeuken node[979]: *** WARNING *** For more information see Nov 28 17:52:44 oradiokeuken volumio[979]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 28 17:52:44 oradiokeuken volumio[979]: info: Discovery: Started advertising with name: OradioKeuken Nov 28 17:52:44 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 17:52:44 oradiokeuken volumio[979]: info: Loading plugin "spop"... Nov 28 17:52:45 oradiokeuken volumio[979]: info: Loading plugin "outputs"... Nov 28 17:52:45 oradiokeuken volumio[979]: info: Loading plugin "albumart"... Nov 28 17:52:45 oradiokeuken volumio[979]: info: Plugin example_plugin is not enabled Nov 28 17:52:45 oradiokeuken volumio[979]: info: Loading plugin "inputs"... Nov 28 17:52:45 oradiokeuken volumio[979]: info: Loading plugin "updater_comm"... Nov 28 17:52:45 oradiokeuken sudo[1044]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:46 oradiokeuken volumio[979]: info: Plugin mpdemulation is not enabled Nov 28 17:52:46 oradiokeuken volumio[979]: info: Loading plugin "rest_api"... Nov 28 17:52:46 oradiokeuken volumio[979]: info: Loading plugin "websocket"... Nov 28 17:52:46 oradiokeuken volumio[979]: info: Starting Socket.io Server version 2.3.0 Nov 28 17:52:46 oradiokeuken volumio[979]: info: Loading plugin "Systeminfo"... Nov 28 17:52:46 oradiokeuken volumio[979]: Forking 3 albumart workers Nov 28 17:52:46 oradiokeuken systemd[1]: systemd-hostnamed.service: Succeeded. Nov 28 17:52:46 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:46] [connect] Successful connection Nov 28 17:52:46 oradiokeuken volumio[979]: info: Loading i18n strings for locale nl Nov 28 17:52:46 oradiokeuken volumio[979]: Updating browse sources language Nov 28 17:52:46 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::initPlayerControls Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: Express server listening on port 3000 Nov 28 17:52:47 oradiokeuken volumio[979]: [Metrics] WebUI: 8s 784.32ms Nov 28 17:52:47 oradiokeuken volumio[979]: Starting albumart workers Nov 28 17:52:47 oradiokeuken volumio[979]: Starting albumart workers Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::resetVolumioState Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::getcurrentVolume Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 17:52:47 oradiokeuken volumio[979]: Starting albumart workers Nov 28 17:52:47 oradiokeuken volumio[979]: info: Volumio Network Manager: Network status updated: 2 Nov 28 17:52:47 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.81 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 28 17:52:47 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.81 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 28 17:52:47 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.81 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 28 17:52:47 oradiokeuken volumio-remote-updater[588]: [2024-11-28 17:52:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732812766 101 Nov 28 17:52:47 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Nov 28 17:52:47 oradiokeuken volumio[979]: info: Reloading queue from file Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::setRepeat true single undefined Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:52:47 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::setRandom true Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:52:47 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:52:47 oradiokeuken volumio[979]: info: VolumeController:: Volume=26 Mute =false Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:52:47 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreStateMachine::updateTrackBlock Nov 28 17:52:47 oradiokeuken volumio[979]: info: CorePlayQueue::getTrackBlock Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioRetrievevolume Nov 28 17:52:47 oradiokeuken volumio[979]: info: Setting Device type: Raspberry PI Nov 28 17:52:47 oradiokeuken volumio[979]: info: DAC script executed Nov 28 17:52:47 oradiokeuken volumio[979]: info: Completed loading Core Plugins Nov 28 17:52:47 oradiokeuken volumio[979]: info: Preparing to generate the ALSA configuration file Nov 28 17:52:47 oradiokeuken volumio[979]: info: The plugin Systeminfo has an ALSA contribution file in.out.conf Nov 28 17:52:47 oradiokeuken volumio[979]: info: Reading ALSA contributions from plugins. Nov 28 17:52:47 oradiokeuken volumio[979]: info: Asound.conf file unchanged, so no further update is needed Nov 28 17:52:47 oradiokeuken volumio[979]: info: Output device has changed, restarting MPD Nov 28 17:52:47 oradiokeuken volumio[979]: info: Output device has changed, restarting Shairport Sync Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:47 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:48 oradiokeuken sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 17:52:48 oradiokeuken sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 17:52:48 oradiokeuken sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:48 oradiokeuken sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:48 oradiokeuken sudo[1146]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:48 oradiokeuken volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 17:52:48 oradiokeuken volumio[979]: info: ___________ START PLUGINS ___________ Nov 28 17:52:48 oradiokeuken systemd[1]: Stopping Music Player Daemon... Nov 28 17:52:48 oradiokeuken volumio[979]: info: ControllerMpd::onStart: Initializing MPD Nov 28 17:52:48 oradiokeuken volumio[979]: info: Creating MPD Configuration file Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 17:52:48 oradiokeuken volumio[979]: info: [1732812768074] CoreMusicLibrary::Adding element Media Servers Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:48 oradiokeuken sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 17:52:48 oradiokeuken sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:48 oradiokeuken sudo[1155]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:48 oradiokeuken sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 17:52:48 oradiokeuken sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 17:52:48 oradiokeuken volumio[979]: info: [1732812768213] CoreMusicLibrary::Adding element Last_100 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 17:52:48 oradiokeuken volumio[979]: info: [1732812768216] CoreMusicLibrary::Adding element Webradio Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 17:52:48 oradiokeuken volumio[979]: info: Initializing BBC Radios Nov 28 17:52:48 oradiokeuken volumio[979]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: Creating Spotify config file Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: Volumio Calling Home Nov 28 17:52:48 oradiokeuken systemd[1]: mpd.service: Succeeded. Nov 28 17:52:48 oradiokeuken systemd[1]: Stopped Music Player Daemon. Nov 28 17:52:48 oradiokeuken systemd[1]: Starting Music Player Daemon... Nov 28 17:52:48 oradiokeuken volumio[979]: info: Discovery: adding fd46e76b-cd24-4b21-a880-ecc4bf3e9587 Nov 28 17:52:48 oradiokeuken volumio[979]: info: Discovery: Found device OradioKeuken Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:48 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:48 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.81 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 28 17:52:48 oradiokeuken volumio[979]: info: MPD Permissions set Nov 28 17:52:48 oradiokeuken volumio[979]: info: VolumeController:: Volume=26 Mute =false Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:52:48 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:52:48 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.81 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 28 17:52:48 oradiokeuken volumio[979]: info: Spotify config file written Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 17:52:48 oradiokeuken sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 28 17:52:48 oradiokeuken sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:48 oradiokeuken volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken sudo[1178]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 17:52:48 oradiokeuken sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:48 oradiokeuken sudo[1178]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: No need to fix Spotify hosts Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:48 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:48 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 17:52:48 oradiokeuken systemd[1]: Started go-librespot Daemon. Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 17:52:48 oradiokeuken go-librespot[1185]: Librespot-go daemon starting... Nov 28 17:52:48 oradiokeuken volumio[979]: info: Received Get System Info Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 17:52:48 oradiokeuken volumio[979]: info: Discovery: Getting this device information Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:48 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:48 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:48 oradiokeuken sudo[1181]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:48 oradiokeuken volumio[979]: info: Listing playlists Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetQueue Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreStateMachine::getQueue Nov 28 17:52:48 oradiokeuken volumio[979]: info: CorePlayQueue::getQueue Nov 28 17:52:48 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 17:52:49 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 192.168.1.115 from 192.168.1.81 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetQueue Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreStateMachine::getQueue Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getQueue Nov 28 17:52:49 oradiokeuken volumio[979]: info: Listing playlists Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 17:52:49 oradiokeuken volumio[979]: info: Received Get System Info Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 17:52:49 oradiokeuken volumio[979]: info: Discovery: Getting this device information Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:49 oradiokeuken volumio[979]: info: Volumio called home Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 17:52:49 oradiokeuken volumio[979]: info: Starting Shairport Sync Nov 28 17:52:49 oradiokeuken volumio[979]: info: Starting Shairport Sync Nov 28 17:52:49 oradiokeuken sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 17:52:49 oradiokeuken volumio[979]: info: Starting Shairport Sync Nov 28 17:52:49 oradiokeuken sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:49 oradiokeuken sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 17:52:49 oradiokeuken volumio[979]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 28 17:52:49 oradiokeuken volumio[979]: SPOTIFY: BQDZFdMeA5DhZlcx-B_5Ijy02iQNL0Zez8-czK14LpDz03q31UBhi_eC7M4MNCEA8bThy8bx6ZjDAG9FcbRZmjwKu_3vSMzLDV55f9OTggYFMrcPXOQZ0akeFMKwalvqR3OLG2aRgl_MvFxUxfCnF4_mb20r1s9Kut-X8eM55d0lJx9xMsLvL-ydcIeneShxugpXZuVZUULFy65rl62eq2w1knDX1tKxr9lJiE5qHH3rYwUdVF-WjSvn-F436y9AOAg Nov 28 17:52:49 oradiokeuken sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:49 oradiokeuken volumio[979]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 28 17:52:49 oradiokeuken sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 17:52:49 oradiokeuken systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 17:52:49 oradiokeuken volumio[979]: info: New Spotify access token = BQDZFdMeA5DhZlcx-B_5Ijy02iQNL0Zez8-czK14LpDz03q31UBhi_eC7M4MNCEA8bThy8bx6ZjDAG9FcbRZmjwKu_3vSMzLDV55f9OTggYFMrcPXOQZ0akeFMKwalvqR3OLG2aRgl_MvFxUxfCnF4_mb20r1s9Kut-X8eM55d0lJx9xMsLvL-ydcIeneShxugpXZuVZUULFy65rl62eq2w1knDX1tKxr9lJiE5qHH3rYwUdVF-WjSvn-F436y9AOAg Nov 28 17:52:49 oradiokeuken sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:49 oradiokeuken systemd[1]: shairport-sync.service: Succeeded. Nov 28 17:52:49 oradiokeuken volumio[979]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 28 17:52:49 oradiokeuken systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetQueue Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreStateMachine::getQueue Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getQueue Nov 28 17:52:49 oradiokeuken volumio[979]: info: Listing playlists Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 17:52:49 oradiokeuken volumio[979]: info: Received Get System Info Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 17:52:49 oradiokeuken volumio[979]: info: Discovery: Getting this device information Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:49 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:49 oradiokeuken systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 17:52:49 oradiokeuken sudo[1202]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:49 oradiokeuken systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 17:52:49 oradiokeuken systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 28 17:52:49 oradiokeuken systemd[1]: shairport-sync.service: Succeeded. Nov 28 17:52:49 oradiokeuken systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 17:52:49 oradiokeuken systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 17:52:49 oradiokeuken sudo[1207]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:49 oradiokeuken sudo[1204]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:49 oradiokeuken volumio[979]: info: Shairport-Sync Started Nov 28 17:52:49 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:49+01:00" level=info msg="generated new device id: 1d3ba5e0d217620653665c9db3164e1436de3769" Nov 28 17:52:49 oradiokeuken volumio[979]: Error adding Membership: Error: addMembership EINVAL Nov 28 17:52:49 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:49+01:00" level=debug msg="stored credentials found for olaf.mastenbroek" Nov 28 17:52:49 oradiokeuken volumio[979]: info: Shairport-Sync Started Nov 28 17:52:49 oradiokeuken volumio[979]: info: Shairport-Sync Started Nov 28 17:52:49 oradiokeuken volumio[979]: SPOTIFY: User informations: {"country":"NL","display_name":"olaf.mastenbroek","email":"olaf.mastenbroek@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/olaf.mastenbroek"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/olaf.mastenbroek","id":"olaf.mastenbroek","images":[],"product":"premium","type":"user","uri":"spotify:user:olaf.mastenbroek"} Nov 28 17:52:49 oradiokeuken volumio[979]: info: Spotify Successfully logged in Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 17:52:49 oradiokeuken volumio[979]: info: [1732812769626] CoreMusicLibrary::Adding element Spotify Nov 28 17:52:49 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:52:49 oradiokeuken volumio[979]: Cannot find translation for source Spotify Nov 28 17:52:49 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 28 17:52:49 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 28 17:52:49 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 28 17:52:49 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:49+01:00" level=debug msg="zeroconf server listening on port 41455" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="obtained new client token: AACB9i8aNBmQKOPMeje3/+ANXYfq+8Z4nGJIn9/QJm2zLidm0MGQzprXzb80QCVjSWDBbQrLNsEkd5KuqNvevK1szMWjtVqBLd6Fg4MUZ75sMT5rYKAwwQzszgWe++7dTq/rEdy1uzwZLAcKTu1UKLAbo58MhLKCqdv0M+cyhz8JrKuLVCC2qWRtMtKmzqCdXpZj0Gh7Y5yhgIJRlrOApHaVAZpmul0sX10Vy2/0hvTFaUvfKwo00NNhfcxK" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="completed keyexchange" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="completed challenge" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="authenticated as olaf.mastenbroek" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="authenticated as olaf.mastenbroek" Nov 28 17:52:50 oradiokeuken mpd[1194]: Nov 28 17:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="dealer connection opened" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="initializing zeroconf session, username: olaf.mastenbroek" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="autoplay enabled: false" Nov 28 17:52:50 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="received connection id: Mjk0MTYzZjctMDVmOC00N2M0LWJiY2ItYzA2NDI3YTY1MmE3K2RlYWxlcit0Y3A6Ly8wYWNhNThmNC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQ0Y3N0M0OUJFRjYyMDY4MUI5MEYyQzc0OEU3NTlFRTJEQkRERkQ5NkQxQjI5OEYzRjIzQkYxMDgwRjI4NDAyNw==" Nov 28 17:52:50 oradiokeuken systemd[1]: Started Music Player Daemon. Nov 28 17:52:50 oradiokeuken sudo[1157]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:51 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:50+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 28 17:52:51 oradiokeuken volumio[979]: info: Completed starting Core Plugins Nov 28 17:52:51 oradiokeuken volumio[979]: info: ------------------------------------------- Nov 28 17:52:51 oradiokeuken volumio[979]: info: ----- MyVolumio plugins startup ---- Nov 28 17:52:51 oradiokeuken volumio[979]: info: ------------------------------------------- Nov 28 17:52:51 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 28 17:52:50 oradiokeuken sudo[1148]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:51 oradiokeuken volumio[979]: error: MPD error: The expression evaluated to a falsy value: Nov 28 17:52:51 oradiokeuken volumio[979]: assert.ok(self.idling) Nov 28 17:52:51 oradiokeuken volumio[979]: error: The expression evaluated to a falsy value: Nov 28 17:52:51 oradiokeuken volumio[979]: assert.ok(self.idling) Nov 28 17:52:51 oradiokeuken volumio[979]: error: Cannot initialize MPD Connection: MPD is not running Nov 28 17:52:51 oradiokeuken volumio[979]: error: updateQueue error: null Nov 28 17:52:52 oradiokeuken volumio[979]: info: go-librespot daemon successfully initialized Nov 28 17:52:52 oradiokeuken sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 17:52:52 oradiokeuken sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:52 oradiokeuken sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 17:52:52 oradiokeuken sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:52 oradiokeuken sudo[1240]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:52 oradiokeuken sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 28 17:52:52 oradiokeuken sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:52:52 oradiokeuken sudo[1238]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:52 oradiokeuken systemd[1]: Started UPnP Renderer front-end to MPD. Nov 28 17:52:52 oradiokeuken sudo[1245]: pam_unix(sudo:session): session closed for user root Nov 28 17:52:52 oradiokeuken volumio[979]: info: Upmpdcli Daemon Started Nov 28 17:52:53 oradiokeuken volumio[1248]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 28 17:52:55 oradiokeuken volumio[979]: info: Initializing connection to go-librespot Websocket Nov 28 17:52:55 oradiokeuken go-librespot[1185]: time="2024-11-28T17:52:55+01:00" level=debug msg="new websocket client" Nov 28 17:52:55 oradiokeuken volumio[979]: info: Connection to go-librespot Websocket established Nov 28 17:52:58 oradiokeuken volumio[979]: info: Getting Spotify volume Nov 28 17:52:58 oradiokeuken volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 28 17:52:58 oradiokeuken volumio[979]: info: Spotify volume: 100 Nov 28 17:52:58 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:52:58 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:52:58 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Nov 28 17:52:58 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 100 Nov 28 17:52:58 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 26 Nov 28 17:52:58 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 17:52:58 oradiokeuken volumio[979]: info: Setting Spotify Volume from Volumio: 26 Nov 28 17:52:59 oradiokeuken kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 28 17:52:59 oradiokeuken volumio[979]: info: Adding plugin bluetooth to MyMusic Plugins Nov 28 17:52:59 oradiokeuken volumio[979]: info: Adding plugin multiroom to MyMusic Plugins Nov 28 17:52:59 oradiokeuken volumio[979]: info: Adding plugin metavolumio to MyMusic Plugins Nov 28 17:52:59 oradiokeuken volumio[979]: info: Adding plugin cd_controller to MyMusic Plugins Nov 28 17:52:59 oradiokeuken volumio[979]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 28 17:52:59 oradiokeuken volumio[979]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 28 17:52:59 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 28 17:53:01 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 28 17:53:01 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 28 17:53:01 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:01 oradiokeuken volumio[979]: info: Starting MyVolumio Remote Streaming Endpoints Nov 28 17:53:01 oradiokeuken volumio[979]: info: MyVolumio login type: Token Nov 28 17:53:01 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 28 17:53:01 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 28 17:53:02 oradiokeuken volumio[979]: info: Starting Streaming Service Transparent Proxy Nov 28 17:53:02 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 28 17:53:02 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 28 17:53:02 oradiokeuken volumio[979]: info: Streaming services startup Nov 28 17:53:02 oradiokeuken volumio[979]: info: Starting Streaming Daemon Nov 28 17:53:02 oradiokeuken volumio[979]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 28 17:53:02 oradiokeuken sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 28 17:53:02 oradiokeuken sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:53:02 oradiokeuken volumio[979]: SPOTIFY: SETTING SPOTIFY VOLUME 26 Nov 28 17:53:02 oradiokeuken volumio[979]: info: Sending Spotify command with payload to local API: /player/volume Nov 28 17:53:02 oradiokeuken sudo[1294]: pam_unix(sudo:session): session closed for user root Nov 28 17:53:02 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:02+01:00" level=debug msg="update volume to 17039/65535" Nov 28 17:53:02 oradiokeuken volumio[979]: error: Cannot start Volumio Streaming Daemon Nov 28 17:53:02 oradiokeuken volumio[979]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 28 17:53:02 oradiokeuken volumio[979]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 28 17:53:02 oradiokeuken volumio[979]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Nov 28 17:53:02 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:02+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 28 17:53:02 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:02+01:00" level=trace msg="emitting websocket event: volume" Nov 28 17:53:03 oradiokeuken volumio[979]: SPOTIFY: received: {"type":"volume","data":{"value":26,"max":100}} Nov 28 17:53:03 oradiokeuken volumio[979]: SPOTIFY: RECEIVED SPOTIFY VOLUME 26 Nov 28 17:53:03 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume26 Nov 28 17:53:03 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:03 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:03 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:03 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Nov 28 17:53:03 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:03 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:03 oradiokeuken volumio[979]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 28 17:53:03 oradiokeuken volumio[979]: info: MyVolumio token set successfully Nov 28 17:53:03 oradiokeuken volumio[979]: info: MYVOLUMIO: Adding device Nov 28 17:53:03 oradiokeuken volumio[979]: info: MYVOLUMIO: Evaluating Server Nov 28 17:53:04 oradiokeuken volumio[979]: info: MyVolumio status changed Nov 28 17:53:04 oradiokeuken volumio[979]: info: Streaming services startup Nov 28 17:53:04 oradiokeuken volumio[979]: info: Starting Streaming Daemon Nov 28 17:53:04 oradiokeuken volumio[979]: info: Removing browser output: myVolumio user plan is not superstar Nov 28 17:53:04 oradiokeuken volumio[979]: info: Removing audio output: Nov 28 17:53:04 oradiokeuken volumio[979]: info: Stoppping Tunnel 1 Nov 28 17:53:04 oradiokeuken sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 28 17:53:04 oradiokeuken sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:53:04 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:04 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:04 oradiokeuken sudo[1331]: pam_unix(sudo:session): session closed for user root Nov 28 17:53:04 oradiokeuken volumio[979]: error: Cannot start Volumio Streaming Daemon Nov 28 17:53:04 oradiokeuken volumio[979]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 28 17:53:04 oradiokeuken volumio[979]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 28 17:53:04 oradiokeuken volumio[979]: STREAMING PROXY: Starting server on port 3245 Nov 28 17:53:04 oradiokeuken volumio[979]: Node JS runtime: 14 Nov 28 17:53:04 oradiokeuken sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 28 17:53:04 oradiokeuken sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:53:04 oradiokeuken sudo[1333]: pam_unix(sudo:session): session closed for user root Nov 28 17:53:04 oradiokeuken volumio[979]: info: Remote SSH Stopped Nov 28 17:53:04 oradiokeuken volumio[979]: info: Setting Geolocation for MyVolumio to eu6 Nov 28 17:53:04 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:04 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:04 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:04 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:05 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:05 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:05 oradiokeuken volumio[979]: info: Updating MyVolumio device info Nov 28 17:53:05 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:05 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:05 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:05 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:06 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:06 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:06 oradiokeuken volumio[979]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 28 17:53:06 oradiokeuken volumio[1248]: ..................................................................................................................++++ Nov 28 17:53:06 oradiokeuken volumio[979]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 28 17:53:07 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:07 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:08 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:08 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:09 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:09 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:10 oradiokeuken systemd[1]: systemd-timedated.service: Succeeded. Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:10 oradiokeuken volumio[979]: info: MYVOLUMIO: Adding device Nov 28 17:53:10 oradiokeuken volumio[979]: info: MYVOLUMIO: Evaluating Server Nov 28 17:53:10 oradiokeuken volumio[1248]: .................................++++ Nov 28 17:53:10 oradiokeuken volumio[1248]: e is 65537 (0x010001) Nov 28 17:53:10 oradiokeuken volumio[1248]: writing RSA key Nov 28 17:53:10 oradiokeuken kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 28 17:53:10 oradiokeuken volumio[979]: info: Play playlist OradioTest2 Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioClearQueue Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::ClearQueue Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::stop Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::clearPlayQueue Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::saveQueue Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushQueue Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioAddQueueItems Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::addQueueItems Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::addQueueItems Nov 28 17:53:10 oradiokeuken volumio[979]: info: Preload queue cleared Nov 28 17:53:10 oradiokeuken volumio[979]: info: Adding Item to queue: https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushQueue Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::saveQueue Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::updateTrackBlock Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::getTrackBlock Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPlay Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::play index 0 Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::stop Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::play index undefined Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:10 oradiokeuken volumio[979]: info: CoreStateMachine::startPlaybackTimer Nov 28 17:53:10 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:10 oradiokeuken volumio[979]: info: [1732812790902] ControllerWebradio::clearAddPlayTrack Nov 28 17:53:10 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 17:53:10 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Nov 28 17:53:10 oradiokeuken volumio[979]: info: sendMpdCommand stop took 11 milliseconds Nov 28 17:53:10 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 17:53:10 oradiokeuken volumio[979]: info: sendMpdCommand clear took 8 milliseconds Nov 28 17:53:10 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand load "https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein" Nov 28 17:53:10 oradiokeuken volumio[979]: info: Nov 28 17:53:10 oradiokeuken volumio[979]: ---------------------------- MPD announces system playlist update Nov 28 17:53:10 oradiokeuken volumio[979]: info: Ignoring MPD Status Update Nov 28 17:53:10 oradiokeuken volumio[979]: info: Nov 28 17:53:10 oradiokeuken volumio[979]: ---------------------------- MPD announces system playlist update Nov 28 17:53:10 oradiokeuken volumio[979]: info: Ignoring MPD Status Update Nov 28 17:53:11 oradiokeuken volumio[979]: info: Setting Geolocation for MyVolumio to eu10 Nov 28 17:53:11 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:11 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:11 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:11 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:11 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:11 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:11 oradiokeuken volumio[979]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 28 17:53:12 oradiokeuken volumio[979]: info: Updating MyVolumio device info Nov 28 17:53:12 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:12 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:12 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:12 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:12 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:12 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:12 oradiokeuken volumio[979]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 28 17:53:13 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:13 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:13 oradiokeuken systemd[1]: Stopping Music Player Daemon... Nov 28 17:53:13 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand add "https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein" Nov 28 17:53:13 oradiokeuken volumio[979]: error: Upnp client error: Error: read ECONNRESET Nov 28 17:53:13 oradiokeuken volumio[979]: error: MPD error: Error: read ECONNRESET Nov 28 17:53:14 oradiokeuken systemd[1]: mpd.service: Succeeded. Nov 28 17:53:14 oradiokeuken systemd[1]: Stopped Music Player Daemon. Nov 28 17:53:14 oradiokeuken systemd[1]: Starting Music Player Daemon... Nov 28 17:53:14 oradiokeuken sudo[1468]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 17:53:14 oradiokeuken sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:53:14 oradiokeuken sudo[1468]: pam_unix(sudo:session): session closed for user root Nov 28 17:53:14 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:14 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:15 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:15 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:15 oradiokeuken mpd[1470]: Nov 28 17:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 17:53:15 oradiokeuken systemd[1]: Started Music Player Daemon. Nov 28 17:53:15 oradiokeuken mpd_monitor.sh[544]: MPD restarted due to no mpc output. Nov 28 17:53:16 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 17:53:16 oradiokeuken volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 28 17:53:16 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:16 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:16 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:16 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:16 oradiokeuken volumio[979]: error: Upnp client error: Error: This socket has been ended by the other party Nov 28 17:53:17 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:17 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:18 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:18 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:18 oradiokeuken volumio[979]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Nov 28 17:53:18 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:18 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 17:53:18 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:18 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 17:53:18 oradiokeuken volumio[979]: error: updateQueue error: null Nov 28 17:53:18 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 28 17:53:18 oradiokeuken volumio-remote-updater[588]: No test mode Nov 28 17:53:18 oradiokeuken volumio-remote-updater[588]: No alpha test mode Nov 28 17:53:18 oradiokeuken volumio[979]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 28 17:53:18 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 28 17:53:19 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:19 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:20 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:20 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:20 oradiokeuken ntpd[733]: Soliciting pool server 185.51.192.61 Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 17:53:21 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:21 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:21 oradiokeuken ntpd[733]: Soliciting pool server 83.84.240.223 Nov 28 17:53:21 oradiokeuken ntpd[733]: Soliciting pool server 213.109.127.82 Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:22 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:22 oradiokeuken ntpd[733]: Soliciting pool server 45.138.55.61 Nov 28 17:53:22 oradiokeuken ntpd[733]: Soliciting pool server 5.255.99.180 Nov 28 17:53:22 oradiokeuken volumio[979]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 28 17:53:22 oradiokeuken volumio[979]: info: Completed starting MyVolumio Plugin Nov 28 17:53:22 oradiokeuken volumio[979]: [Metrics] CommandRouter: 43s 641.56ms Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::volumiosetStartupVolume Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:53:22 oradiokeuken volumio[979]: info: VolumeController:: Setting startup Volume 10 Nov 28 17:53:22 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume10 Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::Close All Modals sent Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::Close All Modals sent Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:22 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:22 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:22 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Nov 28 17:53:22 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 26 Nov 28 17:53:22 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 10 Nov 28 17:53:22 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 17:53:22 oradiokeuken volumio[979]: info: Setting Spotify Volume from Volumio: 10 Nov 28 17:53:23 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:23 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:23 oradiokeuken ntpd[733]: Soliciting pool server 94.198.159.11 Nov 28 17:53:23 oradiokeuken ntpd[733]: Soliciting pool server 94.198.159.15 Nov 28 17:53:23 oradiokeuken ntpd[733]: Soliciting pool server 185.224.145.68 Nov 28 17:53:23 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 28 17:53:23 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 28 17:53:23 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 28 17:53:24 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:24 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:24 oradiokeuken ntpd[733]: Soliciting pool server 144.21.38.190 Nov 28 17:53:24 oradiokeuken ntpd[733]: Soliciting pool server 84.82.15.107 Nov 28 17:53:24 oradiokeuken volumio[979]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Nov 28 17:53:24 oradiokeuken volumio[979]: info: Sending Spotify command with payload to local API: /player/volume Nov 28 17:53:24 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:24+01:00" level=debug msg="update volume to 6553/65535" Nov 28 17:53:24 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 28 17:53:24 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:24+01:00" level=trace msg="emitting websocket event: volume" Nov 28 17:53:24 oradiokeuken volumio[979]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} Nov 28 17:53:24 oradiokeuken volumio[979]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 Nov 28 17:53:25 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:25 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:25 oradiokeuken ntpd[733]: Soliciting pool server 87.238.174.133 Nov 28 17:53:25 oradiokeuken ntpd[733]: Soliciting pool server 193.123.56.220 Nov 28 17:53:26 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:26 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:26 oradiokeuken ntpd[733]: Soliciting pool server 178.239.19.58 Nov 28 17:53:26 oradiokeuken ntpd[733]: Soliciting pool server 2a10:3781:2d18::33 Nov 28 17:53:27 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:27 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:27 oradiokeuken ntpd[733]: Soliciting pool server 185.51.192.63 Nov 28 17:53:27 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:27 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:28 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:28 oradiokeuken ntpd[733]: Soliciting pool server 178.215.228.24 Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioNext Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreStateMachine::next Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreStateMachine::stop Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreStateMachine::play index undefined Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:53:28 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreStateMachine::startPlaybackTimer Nov 28 17:53:28 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:28 oradiokeuken volumio[979]: info: [1732812808449] ControllerWebradio::clearAddPlayTrack Nov 28 17:53:28 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 17:53:28 oradiokeuken volumio[979]: info: CoreStateMachine::updateTrackBlock Nov 28 17:53:28 oradiokeuken volumio[979]: info: CorePlayQueue::getTrackBlock Nov 28 17:53:28 oradiokeuken volumio[979]: info: sendMpdCommand stop took 3 milliseconds Nov 28 17:53:28 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 17:53:28 oradiokeuken volumio[979]: info: Nov 28 17:53:28 oradiokeuken volumio[979]: ---------------------------- MPD announces system playlist update Nov 28 17:53:28 oradiokeuken volumio[979]: info: Ignoring MPD Status Update Nov 28 17:53:28 oradiokeuken volumio[979]: info: sendMpdCommand clear took 2 milliseconds Nov 28 17:53:28 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand load "https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein" Nov 28 17:53:28 oradiokeuken volumio[979]: error: updateQueue error: null Nov 28 17:53:28 oradiokeuken volumio[979]: info: ------------------------------ 5ms Nov 28 17:53:29 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:29 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:30 oradiokeuken ntpd[733]: receive: Unexpected origin timestamp 0xeaf31e89.6ec390da does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeaf31e8a.3aa234d6 Nov 28 17:53:30 oradiokeuken ntpd[733]: receive: Unexpected origin timestamp 0xeaf31e89.6ee6b3ab does not match aorg 0000000000.00000000 from server@45.138.55.61 xmt 0xeaf31e8a.3b58f951 Nov 28 17:53:30 oradiokeuken ntpd[733]: receive: Unexpected origin timestamp 0xeaf31e89.6ee40f20 does not match aorg 0000000000.00000000 from server@94.198.159.15 xmt 0xeaf31e8a.3b4191e4 Nov 28 17:53:30 oradiokeuken ntpd[733]: receive: Unexpected origin timestamp 0xeaf31e89.6ee15c5a does not match aorg 0000000000.00000000 from server@185.224.145.68 xmt 0xeaf31e8a.3c0e88bd Nov 28 17:53:30 oradiokeuken ntpd[733]: receive: Unexpected origin timestamp 0xeaf31e89.6edddf6d does not match aorg 0000000000.00000000 from server@84.82.15.107 xmt 0xeaf31e8a.3be33098 Nov 28 17:53:30 oradiokeuken ntpd[733]: receive: Unexpected origin timestamp 0xeaf31e89.6ee94cdb does not match aorg 0000000000.00000000 from server@83.84.240.223 xmt 0xeaf31e8a.3928a770 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Play playlist OradioTest3 Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioClearQueue Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreStateMachine::ClearQueue Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreStateMachine::stop Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:53:30 oradiokeuken volumio[979]: info: CorePlayQueue::clearPlayQueue Nov 28 17:53:30 oradiokeuken volumio[979]: info: CorePlayQueue::saveQueue Nov 28 17:53:30 oradiokeuken kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushQueue Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioAddQueueItems Nov 28 17:53:30 oradiokeuken volumio[979]: info: CoreStateMachine::addQueueItems Nov 28 17:53:30 oradiokeuken volumio[979]: info: CorePlayQueue::addQueueItems Nov 28 17:53:30 oradiokeuken volumio[979]: info: Preload queue cleared Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:7HB1REjjalFLSXgXlAAwFs Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:7HB1REjjalFLSXgXlAAwFs in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:7HB1REjjalFLSXgXlAAwFs Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:2GBPhbK8skoBvczwq2Uwc2 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:2GBPhbK8skoBvczwq2Uwc2 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:2GBPhbK8skoBvczwq2Uwc2 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:5ZiyLuFvDbFwskqHpJ8E3X Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:5ZiyLuFvDbFwskqHpJ8E3X in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:5ZiyLuFvDbFwskqHpJ8E3X Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:7KHG8MHzxAv0YnLFBWY9Y1 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:7KHG8MHzxAv0YnLFBWY9Y1 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:7KHG8MHzxAv0YnLFBWY9Y1 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:3OSBkT1q8dSQR7q5xJlR2p Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:3OSBkT1q8dSQR7q5xJlR2p in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:3OSBkT1q8dSQR7q5xJlR2p Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4sCgQZkwhYlB1a8ocpiWRr Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4sCgQZkwhYlB1a8ocpiWRr in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4sCgQZkwhYlB1a8ocpiWRr Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:2qLKK0wOOEvkXqxu7X3WTn Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:2qLKK0wOOEvkXqxu7X3WTn in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:2qLKK0wOOEvkXqxu7X3WTn Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:69no5UFvU1Lh0rhzus3H1l Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:69no5UFvU1Lh0rhzus3H1l in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:69no5UFvU1Lh0rhzus3H1l Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:3Vji5BYcceyvEOBNCHsVrx Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:3Vji5BYcceyvEOBNCHsVrx in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:3Vji5BYcceyvEOBNCHsVrx Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:3MHiOpWCWQQgJAplRWxsbJ Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:3MHiOpWCWQQgJAplRWxsbJ in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:3MHiOpWCWQQgJAplRWxsbJ Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:5FOHcVVCLe3e7hA1nP4bv9 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:5FOHcVVCLe3e7hA1nP4bv9 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:5FOHcVVCLe3e7hA1nP4bv9 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:2HrDeUcfs5MGPKRo8gD1BI Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:2HrDeUcfs5MGPKRo8gD1BI in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:2HrDeUcfs5MGPKRo8gD1BI Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:0Z8N2t2ESzixtZAOOiQYEu Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:0Z8N2t2ESzixtZAOOiQYEu in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:0Z8N2t2ESzixtZAOOiQYEu Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:3jRW4o7TpIjA6KCnpqIeR6 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:3jRW4o7TpIjA6KCnpqIeR6 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:3jRW4o7TpIjA6KCnpqIeR6 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:60z1keK5l3CStQxGI8qho7 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:60z1keK5l3CStQxGI8qho7 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:60z1keK5l3CStQxGI8qho7 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4ghuuQoeBVcBSXzpN3iyMX Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4ghuuQoeBVcBSXzpN3iyMX in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4ghuuQoeBVcBSXzpN3iyMX Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:1Jzy0lrAkrkgvkmH1ytD1T Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:1Jzy0lrAkrkgvkmH1ytD1T in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:1Jzy0lrAkrkgvkmH1ytD1T Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4POh6BtxX9K6c8SlhGUOaL Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4POh6BtxX9K6c8SlhGUOaL in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4POh6BtxX9K6c8SlhGUOaL Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:2SvULYdmPes23yZQYeLkXV Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:2SvULYdmPes23yZQYeLkXV in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:2SvULYdmPes23yZQYeLkXV Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:6yhioaNcLd5qwei5S0HQsu Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:6yhioaNcLd5qwei5S0HQsu in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:6yhioaNcLd5qwei5S0HQsu Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:1QWRScJK5eb5BE6ODimqJl Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:1QWRScJK5eb5BE6ODimqJl in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:1QWRScJK5eb5BE6ODimqJl Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:5NiFJ4fKkReA3X8e4Le1kS Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:5NiFJ4fKkReA3X8e4Le1kS in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:5NiFJ4fKkReA3X8e4Le1kS Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:1NgPtVcGpznnZ5KZZziZfq Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:1NgPtVcGpznnZ5KZZziZfq in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:1NgPtVcGpznnZ5KZZziZfq Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4vFEiNPUaEvfWnHwVRa0Kq Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4vFEiNPUaEvfWnHwVRa0Kq in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4vFEiNPUaEvfWnHwVRa0Kq Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:75KVlLfyNRXb4cZ0zNQItx Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:75KVlLfyNRXb4cZ0zNQItx in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:75KVlLfyNRXb4cZ0zNQItx Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:0N4IdBjFHin0kM5x6Er5ut Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:0N4IdBjFHin0kM5x6Er5ut in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:0N4IdBjFHin0kM5x6Er5ut Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:1HO9ytmKDXJZhyFVpDfBMD Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:1HO9ytmKDXJZhyFVpDfBMD in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:1HO9ytmKDXJZhyFVpDfBMD Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4Znqis0gXTTuZupdWToKhK Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4Znqis0gXTTuZupdWToKhK in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4Znqis0gXTTuZupdWToKhK Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:3DPMLNS1sTGikj4I0su7rP Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:3DPMLNS1sTGikj4I0su7rP in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:3DPMLNS1sTGikj4I0su7rP Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:08B8Ki7qlUoT58Zd1trxkl Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:08B8Ki7qlUoT58Zd1trxkl in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:08B8Ki7qlUoT58Zd1trxkl Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:1YzzggaJpWOni5qRz5QKXY Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:1YzzggaJpWOni5qRz5QKXY in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:1YzzggaJpWOni5qRz5QKXY Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:6h7ixSEuRKEDgyRe1EzFKi Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:6h7ixSEuRKEDgyRe1EzFKi in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:6h7ixSEuRKEDgyRe1EzFKi Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4lJP4zoqaBVzZMHTK597Ya Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4lJP4zoqaBVzZMHTK597Ya in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4lJP4zoqaBVzZMHTK597Ya Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:0VQMAup2VdeQWLCvM14Uz2 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:0VQMAup2VdeQWLCvM14Uz2 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:0VQMAup2VdeQWLCvM14Uz2 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4NgkHaeIf8CNBuMxz3ZT32 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4NgkHaeIf8CNBuMxz3ZT32 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4NgkHaeIf8CNBuMxz3ZT32 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:7lAOLNDVHWwvBohU5frfG7 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:7lAOLNDVHWwvBohU5frfG7 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:7lAOLNDVHWwvBohU5frfG7 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:2ZjhhB3kZP6Zufmjuv2TWC Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:2ZjhhB3kZP6Zufmjuv2TWC in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:2ZjhhB3kZP6Zufmjuv2TWC Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4ats4db9aL9O0Dh1i1q3ie Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4ats4db9aL9O0Dh1i1q3ie in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4ats4db9aL9O0Dh1i1q3ie Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:73WeT74sA6tnCl9VtgPbq4 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:73WeT74sA6tnCl9VtgPbq4 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:73WeT74sA6tnCl9VtgPbq4 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4aVQZ6pyDgfxMBVBiJlOtC Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4aVQZ6pyDgfxMBVBiJlOtC in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4aVQZ6pyDgfxMBVBiJlOtC Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4bVgxlsGp0a2lbr3piIfAf Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4bVgxlsGp0a2lbr3piIfAf in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4bVgxlsGp0a2lbr3piIfAf Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:2Y4HXW4ZEUTTCQ3gK4bm4R Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:2Y4HXW4ZEUTTCQ3gK4bm4R in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:2Y4HXW4ZEUTTCQ3gK4bm4R Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:5Mdz067OVfcjWGgz2tKDdu Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:5Mdz067OVfcjWGgz2tKDdu in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:5Mdz067OVfcjWGgz2tKDdu Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:1Ojz92vyPK4fJ5K8RVnV5u Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:1Ojz92vyPK4fJ5K8RVnV5u in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:1Ojz92vyPK4fJ5K8RVnV5u Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:0WMuH7EEhLJjGg8T1DQCNO Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:0WMuH7EEhLJjGg8T1DQCNO in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:0WMuH7EEhLJjGg8T1DQCNO Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:3xGjftrVYEbXopHqiOLI6Z Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:3xGjftrVYEbXopHqiOLI6Z in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:3xGjftrVYEbXopHqiOLI6Z Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:2LYHlX00s7NTqWVrKu7xOE Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:2LYHlX00s7NTqWVrKu7xOE in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:2LYHlX00s7NTqWVrKu7xOE Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:4vSdYf8zbPXtxKU1U0KnPj Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:4vSdYf8zbPXtxKU1U0KnPj in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:4vSdYf8zbPXtxKU1U0KnPj Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:0qxV4vP9Td33eweWijvCK8 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:0qxV4vP9Td33eweWijvCK8 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:0qxV4vP9Td33eweWijvCK8 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Adding Item to queue: spotify:track:13d2MGnsh2SLlC9Xo9bl21 Nov 28 17:53:30 oradiokeuken volumio[979]: info: Exploding uri spotify:track:13d2MGnsh2SLlC9Xo9bl21 in service spop Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: EXPLODING URI:spotify:track:13d2MGnsh2SLlC9Xo9bl21 Nov 28 17:53:30 oradiokeuken volumio[979]: info: BOOT COMPLETED Nov 28 17:53:30 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Nov 28 17:53:31 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand add "https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein" Nov 28 17:53:31 oradiokeuken volumio[979]: info: Nov 28 17:53:31 oradiokeuken volumio[979]: ---------------------------- MPD announces system playlist update Nov 28 17:53:31 oradiokeuken volumio[979]: info: Ignoring MPD Status Update Nov 28 17:53:31 oradiokeuken volumio[979]: info: sendMpdCommand add "https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein" took 17 milliseconds Nov 28 17:53:31 oradiokeuken volumio[979]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 17:53:31 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand play Nov 28 17:53:31 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:31 oradiokeuken volumio[979]: info: ------------------------------ 123ms Nov 28 17:53:31 oradiokeuken volumio[979]: info: sendMpdCommand play took 119 milliseconds Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3MHiOpWCWQQgJAplRWxsbJ","service":"spop","name":"Solfeggio, H. 220","artist":"Carl Philipp Emanuel Bach","album":"AMEB Piano For Leisure Series 1 Grade 4","type":"song","duration":73,"albumart":"https://i.scdn.co/image/ab67616d0000b273efafe12034d49e405c6093ca","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ZiyLuFvDbFwskqHpJ8E3X","service":"spop","name":"Sinfonia for Strings in G major, RV 149: I. Allegro molto","artist":"Karoly Botvay","album":"Vivaldi, A.: The 4 Seasons / Sinfonias, Rv 112, 132, 149 and 169","type":"song","duration":110,"albumart":"https://i.scdn.co/image/ab67616d0000b273d342334578bc993287d687d5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0N4IdBjFHin0kM5x6Er5ut","service":"spop","name":"L'arte dell'arco, B. F11: Variazione No. 4","artist":"Giuseppe Tartini","album":"L'arte dell'arco: Cinquanta variazioni sulla gavotta più bella del Corelli (435 Hz)","type":"song","duration":69,"albumart":"https://i.scdn.co/image/ab67616d0000b273bdd76ad5b17dd83c4231c5d2","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1YzzggaJpWOni5qRz5QKXY","service":"spop","name":"Concerto Grosso in F Major, Op. 1, No. 1: IV. Largo","artist":"Pietro Locatelli","album":"Locatelli: Concerti Grossi, Op. 1, Nos. 1- 6","type":"song","duration":53,"albumart":"https://i.scdn.co/image/ab67616d0000b27315188bb839ea3ca323451da2","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3jRW4o7TpIjA6KCnpqIeR6","service":"spop","name":"Paquita: Variation 5: Allegro non troppo (by Cherepnin)","artist":"Ludwig Minkus","album":"Minkus, L.: Bayadere (La) / Paquita [Ballets]","type":"song","duration":65,"albumart":"https://i.scdn.co/image/ab67616d0000b273a03377c2628257e9aa75dbfc","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1QWRScJK5eb5BE6ODimqJl","service":"spop","name":"The 4 Seasons: Violin Concerto in G minor, Op. 8, No. 2, RV 315, \"L'estate\" (Summer): III. Presto","artist":"Karoly Botvay","album":"Vivaldi, A.: The 4 Seasons / Sinfonias, Rv 112, 132, 149 and 169","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273d342334578bc993287d687d5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4sCgQZkwhYlB1a8ocpiWRr","service":"spop","name":"Cello Concerto in E Minor, RV 409: II. Allegro","artist":"Antonio Vivaldi","album":"Vivaldi: Concertos for 2 Cellos","type":"song","duration":61,"albumart":"https://i.scdn.co/image/ab67616d0000b273ab6e9211df8ef29f3c7fcc8a","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Z8N2t2ESzixtZAOOiQYEu","service":"spop","name":"Sonata for Violin and Guitar in A Minor, Op. 2, No. 6, MS 26: Tempo di Walzer","artist":"Niccolò Paganini","album":"Paganini: Music for Violin and Guitar, Vol. 2","type":"song","duration":98,"albumart":"https://i.scdn.co/image/ab67616d0000b273bcf3df9985c0d33f79b49f6a","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4POh6BtxX9K6c8SlhGUOaL","service":"spop","name":"Suite in G minor: IV. Bourree II (Arr. A. Camden)","artist":"George Frideric Handel","album":"HANDEL: Oboe Concertos Nos. 1- 3 / Suite in G Minor","type":"song","duration":87,"albumart":"https://i.scdn.co/image/ab67616d0000b273b9aeff7e20e3b214f1943e72","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:60z1keK5l3CStQxGI8qho7","service":"spop","name":"Sinfonia in C major, RV 112: I. Allegro","artist":"Karoly Botvay","album":"Vivaldi, A.: The 4 Seasons / Sinfonias, Rv 112, 132, 149 and 169","type":"song","duration":116,"albumart":"https://i.scdn.co/image/ab67616d0000b273d342334578bc993287d687d5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4vFEiNPUaEvfWnHwVRa0Kq","service":"spop","name":"13 Pieces, Op. 76: No. 2. Etude","artist":"Jean Sibelius","album":"Sibelius, J.: 10 Pieces / Jaakarien Marssi / 13 Pieces / 2 Rondinos / 10 Little Pieces","type":"song","duration":72,"albumart":"https://i.scdn.co/image/ab67616d0000b2736f09f01e09042b99aa31cda4","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5FOHcVVCLe3e7hA1nP4bv9","service":"spop","name":"Ballabile","artist":"Blagoje Bersa","album":"Bersa: Complete Piano Works, Vol. 1","type":"song","duration":96,"albumart":"https://i.scdn.co/image/ab67616d0000b273c8563f23aa0e74fd87f824ca","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1HO9ytmKDXJZhyFVpDfBMD","service":"spop","name":"Sinfonia in C major, RV 112: II. Andante","artist":"Karoly Botvay","album":"Vivaldi, A.: The 4 Seasons / Sinfonias, Rv 112, 132, 149 and 169","type":"song","duration":112,"albumart":"https://i.scdn.co/image/ab67616d0000b273d342334578bc993287d687d5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ghuuQoeBVcBSXzpN3iyMX","service":"spop","name":"Sonata in C Major, No. 3: IV. Allegro","artist":"Arcangelo Corelli","album":"Corelli: Violin Sonatas Nos. 1-6, Op. 5","type":"song","duration":62,"albumart":"https://i.scdn.co/image/ab67616d0000b273eeee2ab11507f16230573c2e","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2SvULYdmPes23yZQYeLkXV","service":"spop","name":"21 Hungarian Dances, WoO 1 (version for orchestra): Hungarian Dance No. 5 (orch. Schmeling)","artist":"Johannes Brahms","album":"Brahms: Hungarian Dances Nos. 1-21","type":"song","duration":135,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c553f28095fee83fcb10e3a","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4lJP4zoqaBVzZMHTK597Ya","service":"spop","name":"Concerto in E Minor: II. Vivace","artist":"Benedetto Marcello","album":"Concerti Italiani","type":"song","duration":101,"albumart":"https://i.scdn.co/image/ab67616d0000b273e30eb8b1006670ab00f55163","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:75KVlLfyNRXb4cZ0zNQItx","service":"spop","name":"Abdelazer: Rondeau","artist":"Henry Purcell","album":"Academy of St Martin in the Fields: Baroque Landscapes","type":"song","duration":97,"albumart":"https://i.scdn.co/image/ab67616d0000b2733a20038b99986d352509af9f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6yhioaNcLd5qwei5S0HQsu","service":"spop","name":"21 Hungarian Dances, WoO 1: Hungarian Dance No. 5 in F-Sharp Minor","artist":"Johannes Brahms","album":"Pfitzner: Symphony in C major - Schumann: Koncertstück for Four Horns","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7b0049790088d1115f1984b","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:69no5UFvU1Lh0rhzus3H1l","service":"spop","name":"Abdelazar Suite, Z. 570: VI. Air","artist":"Henry Purcell","album":"Camerata Nordica Plays Baroque","type":"song","duration":68,"albumart":"https://i.scdn.co/image/ab67616d0000b2736ac2acbc244c51c2f5105d11","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2qLKK0wOOEvkXqxu7X3WTn","service":"spop","name":"The Four Seasons (Concerti Op.8 Nos.1-4) Spring (La Primavera) Op.8 No.1 E Major: Allegro","artist":"Baroque Festival Orchestra","album":"Vivaldi - The Four Seasons","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b273b9d4ed70ea90d00612a3f3a7","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2GBPhbK8skoBvczwq2Uwc2","service":"spop","name":"Concerto Grosso In D Major, Op. 6, No. 1 : IV. Allegro","artist":"Arcangelo Corelli","album":"Corelli: Concerti Grossi, Op. 6, Nos. 1-6","type":"song","duration":73,"albumart":"https://i.scdn.co/image/ab67616d0000b273520fd0968a04672b74683fb4","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Vji5BYcceyvEOBNCHsVrx","service":"spop","name":"The 4 Seasons: Violin Concerto in F minor, Op. 8, No. 4, RV 297, \"L'inverno\" (Winter): I. Allegro non molto","artist":"Karoly Botvay","album":"Vivaldi, A.: The 4 Seasons / Sinfonias, Rv 112, 132, 149 and 169","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b273d342334578bc993287d687d5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5NiFJ4fKkReA3X8e4Le1kS","service":"spop","name":"Concerto Grosso In C Minor, Op. 6, No. 3 : III. Grave","artist":"Arcangelo Corelli","album":"Corelli: Concerti Grossi, Op. 6, Nos. 1-6","type":"song","duration":89,"albumart":"https://i.scdn.co/image/ab67616d0000b273520fd0968a04672b74683fb4","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1NgPtVcGpznnZ5KZZziZfq","service":"spop","name":"Concerto for 2 Flutes in C Major, RV 533: II. Largo","artist":"Antonio Vivaldi","album":"Vivaldi: Wind Concertos","type":"song","duration":112,"albumart":"https://i.scdn.co/image/ab67616d0000b2737cc05729c1f7ae4610e2aba7","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Znqis0gXTTuZupdWToKhK","service":"spop","name":"Violin Sonata in B-Flat Major, Op. 5 No. 2: III. Vivace","artist":"Arcangelo Corelli","album":"Corelli: Violin Sonatas, Op. 5 – La gioia","type":"song","duration":81,"albumart":"https://i.scdn.co/image/ab67616d0000b2738fcc42f3482bb8f84260545d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2HrDeUcfs5MGPKRo8gD1BI","service":"spop","name":"Concerto For 4 Violins In B Minor, Op. 3, No. 10, RV 580 : I. Allegro","artist":"Antonio Vivaldi","album":"Vivaldi: L´Estro Armonico, Op. 3","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b27398f0ca62353dce59b91b2077","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3OSBkT1q8dSQR7q5xJlR2p","service":"spop","name":"Suite in G Major, RCT 6: No. 14, Les sauvages - Arr. for Solo Cello - Nouvelles suites de pièces de clavecin","artist":"Jean-Philippe Rameau","album":"Cello 360","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b273111c8e100f7cf285299a0a45","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7HB1REjjalFLSXgXlAAwFs","service":"spop","name":"Concerto for Strings in G Major, RV 151, \"Alla Rustica\": I. Presto","artist":"Antonio Vivaldi","album":"Vivaldi: Concertos for Strings","type":"song","duration":79,"albumart":"https://i.scdn.co/image/ab67616d0000b27328efaa18b542c2c661609641","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Jzy0lrAkrkgvkmH1ytD1T","service":"spop","name":"Mandolin Concerto in C Major, RV 425: I. Allegro","artist":"Antonio Vivaldi","album":"Vivaldi: Musica per liuto e mandolino","type":"song","duration":159,"albumart":"https://i.scdn.co/image/ab67616d0000b27325424268505a40a619506b80","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7KHG8MHzxAv0YnLFBWY9Y1","service":"spop","name":"Le bourgeois gentilhomme: Chaconne des Scaramouches, Frivelins et Arlequins","artist":"Jean-Baptiste Lully","album":"Lully: Ballet Music for the Sun King","type":"song","duration":88,"albumart":"https://i.scdn.co/image/ab67616d0000b2738e0fadf0ac422911ca4fbb15","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ats4db9aL9O0Dh1i1q3ie","service":"spop","name":"Solfeggietto in C Minor, H. 220","artist":"Carl Philipp Emanuel Bach","album":"111 Piano Hits - Vol. 1","type":"song","duration":52,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc16aab5f6fdea503d81dfcf","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ZjhhB3kZP6Zufmjuv2TWC","service":"spop","name":"String Quartet No. 13 in B-Flat Major, Op. 130: II. Presto","artist":"Ludwig van Beethoven","album":"Beethoven: String Quartet, Op. 130 / Grosse Fuge, Op. 133","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d2043a4083f9ed61b1dfa78","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VQMAup2VdeQWLCvM14Uz2","service":"spop","name":"The Four Seasons, Violin Concerto No. 2 in G Minor, RV 315 \"L'estate\": III. Tempo impetuoso d'estate","artist":"Antonio Vivaldi","album":"Vivaldi: The Four Seasons","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b2732578baf17d10bb092635a914","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7lAOLNDVHWwvBohU5frfG7","service":"spop","name":"Waltz No. 10 in B Minor, Op. 69, No. 2","artist":"Frédéric Chopin","album":"Chopin: Waltzes (Complete)","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b27360ad7ccab3a4e24b2343c33b","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4NgkHaeIf8CNBuMxz3ZT32","service":"spop","name":"Sonata in D Major, No. 1: III. Allegro","artist":"Arcangelo Corelli","album":"Corelli: Violin Sonatas Nos. 1-6, Op. 5","type":"song","duration":65,"albumart":"https://i.scdn.co/image/ab67616d0000b273eeee2ab11507f16230573c2e","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6h7ixSEuRKEDgyRe1EzFKi","service":"spop","name":"Le roi s'amuse: Pavane","artist":"Léo Delibes","album":"Delibes: Best of French Ballet","type":"song","duration":88,"albumart":"https://i.scdn.co/image/ab67616d0000b27327e77081a073ecea05f641f1","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:31 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3DPMLNS1sTGikj4I0su7rP","service":"spop","name":"Concerto for Strings in A Minor, RV 161: I. Allegro","artist":"Antonio Vivaldi","album":"Vivaldi: Concertos for Strings","type":"song","duration":98,"albumart":"https://i.scdn.co/image/ab67616d0000b27328efaa18b542c2c661609641","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:08B8Ki7qlUoT58Zd1trxkl","service":"spop","name":"Requiem In D Minor, K. 626: Sequence: VI. Lacrimosa Dies Illa","artist":"Slovak Philharmonic","album":"Mozart: Requiem In D Minor","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b2731c72ad5458bcc5d5650e3093","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:32 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume31 Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 10 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 31 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 17:53:32 oradiokeuken volumio[979]: info: Setting Spotify Volume from Volumio: 31 Nov 28 17:53:32 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume32 Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 31 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 32 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 28 17:53:32 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume33 Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:32 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 31 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 33 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 17:53:32 oradiokeuken volumio[979]: info: Setting Spotify Volume from Volumio: 33 Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Y4HXW4ZEUTTCQ3gK4bm4R","service":"spop","name":"Flute Concerto In F Major, Op. 10, No. 1, RV 433, \"La Tempesta Di Mare\": I. Allegro","artist":"Jiří Válek","album":"Vivaldi: Wind And Brass Concertos","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b27305181d82a10d691074096a34","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4aVQZ6pyDgfxMBVBiJlOtC","service":"spop","name":"Etudes symphoniques (Symphonic Etudes), Op. 13, Adagio and Allegro brillante: Variation 11 [arr. P.I. Tchaikovsky for orchestra]","artist":"Robert Schumann","album":"Pfitzner: Symphony in C major - Schumann: Koncertstück for Four Horns","type":"song","duration":111,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7b0049790088d1115f1984b","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:32 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:73WeT74sA6tnCl9VtgPbq4","service":"spop","name":"Viola d'amore Concerto in A Minor, RV 397: I. Vivace","artist":"Antonio Vivaldi","album":"Vivaldi: The Complete Viola d'amore Concertos","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b27301bfd98780dd42b3fe42a427","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LYHlX00s7NTqWVrKu7xOE","service":"spop","name":"Concerto Grosso In D Major, Op. 6, No. 1 : I. Largo","artist":"Arcangelo Corelli","album":"Corelli: Concerti Grossi, Op. 6, Nos. 1-6","type":"song","duration":68,"albumart":"https://i.scdn.co/image/ab67616d0000b273520fd0968a04672b74683fb4","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3xGjftrVYEbXopHqiOLI6Z","service":"spop","name":"Concerto Saint Marc: III. Andante","artist":"Tomaso Albinoni","album":"Festliche Musik Fur Trompete Und Orgel (Festive Music for Trumpet and Organ)","type":"song","duration":72,"albumart":"https://i.scdn.co/image/ab67616d0000b273a03a65e3eb180acf412e375d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Ojz92vyPK4fJ5K8RVnV5u","service":"spop","name":"The Four Seasons: Concerto No. 2 in G Minor, RV 315 \"L'estate\" (summer): III. Presto","artist":"Antonio Vivaldi","album":"Vivaldi: The Four Seasons • Concertos for Bassoon & Violin 'in tromba marina'","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273577bb481664d06b0b5d5ba58","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:13d2MGnsh2SLlC9Xo9bl21","service":"spop","name":"Partie 2 en ut mineur: Gigue","artist":"Johann Pachelbel","album":"Pachelbel : Musicalische Ergotzung pour 2 violons & basse continue","type":"song","duration":76,"albumart":"https://i.scdn.co/image/ab67616d0000b2737d5e504a7548c1e86bf0036d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Mdz067OVfcjWGgz2tKDdu","service":"spop","name":"The Tale of Tsar Saltan: The Tale of Tsar Saltan: Flight of the Bumblebee","artist":"Nikolai Rimsky-Korsakov","album":"Rimsky-Korsakov: Scheherazade","type":"song","duration":90,"albumart":"https://i.scdn.co/image/ab67616d0000b2734c7de36dc75a56e86c2ee42b","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qxV4vP9Td33eweWijvCK8","service":"spop","name":"Violin Concerto in D Major, RV 228: I. Allegro non troppo ma vigoroso","artist":"Antonio Vivaldi","album":"Vivaldi: Dresden Concertos, Vol. 3","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b27308fc80391d2996d5c09d69ab","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WMuH7EEhLJjGg8T1DQCNO","service":"spop","name":"Sinfonia in E major, RV 132: III. Allegro","artist":"Karoly Botvay","album":"Vivaldi, A.: The 4 Seasons / Sinfonias, Rv 112, 132, 149 and 169","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273d342334578bc993287d687d5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:33 oradiokeuken volumio[979]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4bVgxlsGp0a2lbr3piIfAf","service":"spop","name":"Concerto Grosso In D Major, Op. 6, No. 1 : II. Allegro","artist":"Arcangelo Corelli","album":"Corelli: Concerti Grossi, Op. 6, Nos. 1-6","type":"song","duration":81,"albumart":"https://i.scdn.co/image/ab67616d0000b273520fd0968a04672b74683fb4","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: SETTING SPOTIFY VOLUME 33 Nov 28 17:53:34 oradiokeuken volumio[979]: info: Sending Spotify command with payload to local API: /player/volume Nov 28 17:53:34 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:34+01:00" level=debug msg="update volume to 21626/65535" Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:34 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume35 Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 33 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 35 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 17:53:34 oradiokeuken volumio[979]: info: Setting Spotify Volume from Volumio: 35 Nov 28 17:53:34 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume36 Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:34 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:34+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 28 17:53:34 oradiokeuken go-librespot[1185]: time="2024-11-28T17:53:34+01:00" level=trace msg="emitting websocket event: volume" Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 36 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 35 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 36 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: received: {"type":"volume","data":{"value":33,"max":100}} Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: RECEIVED SPOTIFY VOLUME 33 Nov 28 17:53:34 oradiokeuken volumio[979]: info: Setting Volumio Volume from Spotify: 33 Nov 28 17:53:34 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume33 Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Nov 28 17:53:34 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume36 Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 36 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 33 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 36 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 17:53:34 oradiokeuken volumio[979]: info: Setting Spotify Volume from Volumio: 36 Nov 28 17:53:34 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume37 Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:53:34 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioPushState Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: SPOTIFY VOLUME 36 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: VOLUMIO VOLUME 37 Nov 28 17:53:34 oradiokeuken volumio[979]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:35 oradiokeuken volumio[979]: info: Nov 28 17:53:35 oradiokeuken volumio[979]: ---------------------------- MPD announces state update: player Nov 28 17:53:35 oradiokeuken volumio[979]: info: ControllerMpd::getState Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:53:35 oradiokeuken kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 28 17:53:35 oradiokeuken volumio[979]: info: Nov 28 17:53:35 oradiokeuken volumio[979]: ---------------------------- MPD announces system playlist update Nov 28 17:53:35 oradiokeuken volumio[979]: info: Ignoring MPD Status Update Nov 28 17:53:35 oradiokeuken volumio[979]: info: Nov 28 17:53:35 oradiokeuken volumio[979]: ---------------------------- MPD announces state update: player Nov 28 17:53:35 oradiokeuken volumio[979]: info: sendMpdCommand status took 27 milliseconds Nov 28 17:53:35 oradiokeuken volumio[979]: info: ControllerMpd::getState Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::parseState Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:53:35 oradiokeuken volumio[979]: info: ------------------------------ 10ms Nov 28 17:53:35 oradiokeuken volumio[979]: info: sendMpdCommand status took 7 milliseconds Nov 28 17:53:35 oradiokeuken volumio[979]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::parseState Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:53:35 oradiokeuken volumio[979]: info: ControllerMpd::pushState Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreCommandRouter::servicePushState Nov 28 17:53:35 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joe_nl.mp3?aw_0_1st.playerid=tunein","artist":null,"album":null,"uri":"https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein","trackType":""} Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: CURRENT POSITION 0 Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreStateMachine::syncState stateService play Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:53:35 oradiokeuken volumio[979]: info: ------------------------------ 51ms Nov 28 17:53:35 oradiokeuken volumio[979]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:53:35 oradiokeuken volumio[979]: info: ControllerMpd::pushState Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreCommandRouter::servicePushState Nov 28 17:53:35 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: STATE SERVICE {"status":"play","position":0,"seek":262,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joe_nl.mp3?aw_0_1st.playerid=tunein","artist":null,"album":null,"uri":"https://icecast-qmusicnl-cdp.triple-it.nl/Joe_nl.mp3?aw_0_1st.playerid=tunein","trackType":""} Nov 28 17:53:35 oradiokeuken volumio[979]: verbose: CURRENT POSITION 0 Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreStateMachine::syncState stateService play Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreStateMachine::syncState currentStatus play Nov 28 17:53:35 oradiokeuken volumio[979]: info: Received an update from plugin. extracting info from payload Nov 28 17:53:35 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:35 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:35 oradiokeuken volumio[979]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Nov 28 17:53:35 oradiokeuken volumio[979]: info: ------------------------------ 33ms Nov 28 17:53:36 oradiokeuken volumio[979]: info: VolumeController::SetAlsaVolume36 Nov 28 17:53:36 oradiokeuken volumio[979]: info: CoreStateMachine::pushState Nov 28 17:53:36 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:36 oradiokeuken volumio[979]: info: CoreCommandRouter::volumioGetState Nov 28 17:53:36 oradiokeuken volumio[979]: info: CorePlayQueue::getTrack 0 Nov 28 17:53:36 oradiokeuken volumio[979]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:53:36 oradiokeuken volumio[979]: TypeError: Cannot read property 'name' of undefined Nov 28 17:53:36 oradiokeuken volumio[979]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Nov 28 17:53:36 oradiokeuken volumio[979]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Nov 28 17:53:36 oradiokeuken volumio[979]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Nov 28 17:53:36 oradiokeuken volumio[979]: at Socket.emit (events.js:315:20) Nov 28 17:53:36 oradiokeuken volumio[979]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 28 17:53:36 oradiokeuken volumio[979]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 28 17:53:36 oradiokeuken volumio[979]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:53:36 oradiokeuken sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 17:52 Nov 28 17:53:36 oradiokeuken sudo[1628]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"