-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Fri 2025-07-11 09:12:48 CDT. -- Jul 11 09:11:00 volumio wireless.js[615]: trying... Jul 11 09:11:00 volumio sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:00 volumio sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:00 volumio sudo[1023]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:01 volumio volumio-time-update[599]: volumio-time-update-util: Fetching time from Volumio... Jul 11 09:11:01 volumio volumio-time-update[599]: volumio-time-update-util: Setting system time to: 2025-07-11 09:11:23 Jul 11 09:11:01 volumio sudo[1030]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-11 09:11:23 Jul 11 09:11:01 volumio sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:01 volumio dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=1031 comm="timedatectl set-time 2025-07-11 09:11:23 ") Jul 11 09:11:01 volumio systemd[1]: Starting Time & Date Service... Jul 11 09:11:01 volumio dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 09:11:01 volumio systemd[1]: Started Time & Date Service. Jul 11 09:11:23 volumio systemd-timedated[1032]: Changed local time to Fri Jul 11 09:11:23 2025 Jul 11 09:11:23 volumio sudo[1030]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:23 volumio volumio-time-update[599]: volumio-time-update-util: System time updated successfully. Jul 11 09:11:23 volumio systemd[1]: Started Volumio Time Update Utility. Jul 11 09:11:23 volumio wireless.js[615]: trying... Jul 11 09:11:23 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:23 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:23 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:23 volumio dhcpcd[999]: wlan0: probing for an IPv4LL address Jul 11 09:11:24 volumio wireless.js[615]: trying... Jul 11 09:11:24 volumio sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:24 volumio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:24 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:24 volumio volumio-remote-updater[620]: [2025-07-11 09:11:24] [info] asio async_connect error: system:111 (Connection refused) Jul 11 09:11:24 volumio volumio-remote-updater[620]: [2025-07-11 09:11:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 11 09:11:24 volumio volumio-remote-updater[620]: [2025-07-11 09:11:24] [error] handle_connect error: Underlying Transport Error Jul 11 09:11:25 volumio wireless.js[615]: trying... Jul 11 09:11:25 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:25 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:25 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:26 volumio wireless.js[615]: trying... Jul 11 09:11:26 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:26 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:26 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:27 volumio wireless.js[615]: trying... Jul 11 09:11:27 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:27 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:27 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:28 volumio wireless.js[615]: trying... Jul 11 09:11:28 volumio sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:28 volumio sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:28 volumio sudo[1049]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:28 volumio dhcpcd[999]: wlan0: using IPv4LL address 169.254.192.235 Jul 11 09:11:28 volumio avahi-daemon[600]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.192.235. Jul 11 09:11:28 volumio avahi-daemon[600]: New relevant interface wlan0.IPv4 for mDNS. Jul 11 09:11:28 volumio dhcpcd[999]: wlan0: adding route to 169.254.0.0/16 Jul 11 09:11:28 volumio avahi-daemon[600]: Registering new address record for 169.254.192.235 on wlan0.IPv4. Jul 11 09:11:29 volumio wireless.js[615]: trying... Jul 11 09:11:29 volumio sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 11 09:11:29 volumio sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:29 volumio sudo[1070]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:29 volumio wireless.js[615]: Connected to: ----schoen Jul 11 09:11:29 volumio wireless.js[615]: ---- Jul 11 09:11:29 volumio sudo[1073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 09:11:29 volumio sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:29 volumio sudo[1073]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:29 volumio wireless.js[615]: ... joined AP, wlan0 IPv4 is 169.254.192.235, ipV6 is undefined Jul 11 09:11:29 volumio wireless.js[615]: It's done! AP Jul 11 09:11:29 volumio systemd[1]: Started Wireless Services. Jul 11 09:11:29 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 11 09:11:29 volumio systemd[1]: Started Volumio Backend Module. Jul 11 09:11:29 volumio systemd[1]: Started Volumio Cpu Tweaker. Jul 11 09:11:29 volumio systemd[1]: Reached target Multi-User System. Jul 11 09:11:29 volumio systemd[1]: Reached target Graphical Interface. Jul 11 09:11:29 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 11 09:11:29 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 11 09:11:29 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 11 09:11:29 volumio systemd[1]: Startup finished in 14.064s (kernel) + 23.596s (userspace) = 37.661s. Jul 11 09:11:29 volumio volumio-cpu-tweak[1079]: Setting RT Priority for mpd Jul 11 09:11:29 volumio volumio-cpu-tweak[1079]: Setting MPD Affinity Jul 11 09:11:29 volumio volumio-cpu-tweak[1079]: pid 828's current affinity mask: f Jul 11 09:11:29 volumio volumio-cpu-tweak[1079]: pid 828's new affinity mask: 3 Jul 11 09:11:29 volumio volumio-cpu-tweak[1079]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 11 09:11:29 volumio volumio-cpu-tweak[1079]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 11 09:11:29 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 11 09:11:29 volumio dhcpcd[999]: wlan0: offered 192.168.1.241 from 192.168.1.1 Jul 11 09:11:29 volumio dhcpcd[999]: wlan0: probing address 192.168.1.241/24 Jul 11 09:11:30 volumio ntpd[785]: Listen normally on 4 wlan0 169.254.192.235:123 Jul 11 09:11:30 volumio ntpd[785]: new interface(s) found: waking up resolver Jul 11 09:11:32 volumio volumio[1078]: info: ------------------------------------------- Jul 11 09:11:32 volumio volumio[1078]: info: ----- Volumio3 ---- Jul 11 09:11:32 volumio volumio[1078]: info: ------------------------------------------- Jul 11 09:11:32 volumio volumio[1078]: info: ----- System startup ---- Jul 11 09:11:32 volumio volumio[1078]: info: ------------------------------------------- Jul 11 09:11:33 volumio volumio[1078]: info: MYVOLUMIO Environment detected Jul 11 09:11:33 volumio volumio[1078]: info: Plugin folders cleanup Jul 11 09:11:33 volumio volumio[1078]: info: Scanning into folder /volumio/app/plugins/ Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category audio_interface Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category miscellanea Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category music_service Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category plugins.json Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category system_controller Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category user_interface Jul 11 09:11:33 volumio volumio[1078]: info: Scanning into folder /data/plugins/ Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category music_service Jul 11 09:11:33 volumio volumio[1078]: info: Scanning category user_interface Jul 11 09:11:33 volumio volumio[1078]: info: Plugin folders cleanup completed Jul 11 09:11:33 volumio volumio[1078]: info: ------------------------------------------- Jul 11 09:11:33 volumio volumio[1078]: info: ----- Core plugins startup ---- Jul 11 09:11:33 volumio volumio[1078]: info: ------------------------------------------- Jul 11 09:11:33 volumio volumio[1078]: info: Loading plugins from folder /volumio/app/plugins/ Jul 11 09:11:33 volumio volumio[1078]: info: Adding plugin upnp to MyMusic Plugins Jul 11 09:11:33 volumio volumio[1078]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 11 09:11:33 volumio volumio[1078]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 11 09:11:33 volumio volumio[1078]: info: Loading plugins from folder /data/plugins/ Jul 11 09:11:33 volumio volumio[1078]: info: Loading plugin "system"... Jul 11 09:11:34 volumio volumio[1078]: info: Loading plugin "appearance"... Jul 11 09:11:34 volumio volumio-remote-updater[620]: [2025-07-11 09:11:34] [connect] Successful connection Jul 11 09:11:35 volumio dhcpcd[999]: wlan0: leased 192.168.1.241 for 7200 seconds Jul 11 09:11:35 volumio avahi-daemon[600]: Registering new address record for 192.168.1.241 on wlan0.IPv4. Jul 11 09:11:35 volumio dhcpcd[999]: wlan0: adding route to 192.168.1.0/24 Jul 11 09:11:35 volumio dhcpcd[999]: wlan0: adding default route via 192.168.1.1 Jul 11 09:11:35 volumio avahi-daemon[600]: Withdrawing address record for 169.254.192.235 on wlan0. Jul 11 09:11:35 volumio avahi-daemon[600]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.192.235. Jul 11 09:11:35 volumio avahi-daemon[600]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.241. Jul 11 09:11:35 volumio dhcpcd[999]: wlan0: deleting route to 169.254.0.0/16 Jul 11 09:11:35 volumio volumio[1078]: info: Loading plugin "network"... Jul 11 09:11:35 volumio volumio[1078]: info: Refreshing Cached IP Addresses Jul 11 09:11:35 volumio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 09:11:35 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:35 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:35 volumio sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 09:11:35 volumio sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:35 volumio sudo[1149]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:35 volumio volumio[1078]: info: Loading plugin "services"... Jul 11 09:11:35 volumio volumio[1078]: info: Loading plugin "alsa_controller"... Jul 11 09:11:35 volumio sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 11 09:11:35 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:36 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 09:11:36 volumio volumio[1078]: info: Loading plugin "wizard"... Jul 11 09:11:36 volumio volumio[1078]: info: Loading plugin "networkfs"... Jul 11 09:11:36 volumio volumio[1078]: info: Starting Udev Watcher for removable devices Jul 11 09:11:36 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=LinuxUser,password=acadgod11,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.203/music /mnt/NAS/Music Jul 11 09:11:36 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:36 volumio volumio[1078]: info: Ignoring mount for partition: boot Jul 11 09:11:36 volumio volumio[1078]: info: Ignoring mount for partition: volumio Jul 11 09:11:36 volumio volumio[1078]: info: Ignoring mount for partition: volumio_data Jul 11 09:11:36 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 09:11:36 volumio volumio[1078]: info: Loading plugin "volumio_command_line_client"... Jul 11 09:11:36 volumio volumio[1078]: info: Loading plugin "upnp"... Jul 11 09:11:36 volumio volumio[1078]: info: [1752243096205] Starting Upmpd Daemon Jul 11 09:11:36 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 09:11:36 volumio volumio[1078]: info: Loading plugin "my_music"... Jul 11 09:11:36 volumio volumio[1078]: info: Loading plugin "mpd"... Jul 11 09:11:36 volumio kernel: Key type cifs.spnego registered Jul 11 09:11:36 volumio kernel: Key type cifs.idmap registered Jul 11 09:11:36 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 11 09:11:36 volumio kernel: CIFS: Attempting to mount //192.168.1.203/music Jul 11 09:11:36 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jul 11 09:11:36 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:36 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:36 volumio volumio[1078]: info: Loading plugin "upnp_browser"... Jul 11 09:11:37 volumio volumio[1078]: info: Loading plugin "alarm-clock"... Jul 11 09:11:37 volumio volumio[1078]: info: Loading plugin "airplay_emulation"... Jul 11 09:11:37 volumio volumio[1078]: info: Starting Shairport Sync Jul 11 09:11:37 volumio volumio[1078]: info: Loading plugin "last_100"... Jul 11 09:11:37 volumio volumio[1078]: info: Loading plugin "webradio"... Jul 11 09:11:37 volumio volumio[1078]: info: Loading plugin "i2s_dacs"... Jul 11 09:11:37 volumio volumio[1078]: info: Loading plugin "volumiodiscovery"... Jul 11 09:11:38 volumio volumio[1078]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 11 09:11:38 volumio volumio[1078]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 09:11:38 volumio volumio[1078]: *** WARNING *** For more information see Jul 11 09:11:38 volumio volumio[1078]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 11 09:11:38 volumio node[1078]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 11 09:11:38 volumio volumio[1078]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 09:11:38 volumio volumio[1078]: *** WARNING *** For more information see Jul 11 09:11:38 volumio node[1078]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 09:11:38 volumio node[1078]: *** WARNING *** For more information see Jul 11 09:11:38 volumio node[1078]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 11 09:11:38 volumio node[1078]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 09:11:38 volumio node[1078]: *** WARNING *** For more information see Jul 11 09:11:38 volumio volumio[1078]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 11 09:11:38 volumio volumio[1078]: info: Discovery: Started advertising with name: Volumio Jul 11 09:11:38 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 09:11:38 volumio volumio[1078]: info: Loading plugin "spop"... Jul 11 09:11:38 volumio ntpd[785]: Listen normally on 5 wlan0 192.168.1.241:123 Jul 11 09:11:38 volumio ntpd[785]: Deleting interface #4 wlan0, 169.254.192.235#123, interface stats: received=0, sent=0, dropped=0, active_time=8 secs Jul 11 09:11:38 volumio ntpd[785]: new interface(s) found: waking up resolver Jul 11 09:11:39 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jul 11 09:11:40 volumio volumio[1078]: info: Loading plugin "squeezelite_mc"... Jul 11 09:11:41 volumio volumio[1078]: info: Loading plugin "ytmusic"... Jul 11 09:11:43 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jul 11 09:11:45 volumio volumio[1078]: info: Loading plugin "now_playing"... Jul 11 09:11:47 volumio volumio[1078]: info: Loading plugin "outputs"... Jul 11 09:11:47 volumio volumio[1078]: info: Loading plugin "albumart"... Jul 11 09:11:47 volumio volumio[1078]: info: Plugin example_plugin is not enabled Jul 11 09:11:47 volumio volumio[1078]: info: Loading plugin "inputs"... Jul 11 09:11:47 volumio volumio[1078]: info: Loading plugin "updater_comm"... Jul 11 09:11:48 volumio volumio[1078]: info: Plugin mpdemulation is not enabled Jul 11 09:11:48 volumio volumio[1078]: info: Loading plugin "rest_api"... Jul 11 09:11:48 volumio volumio[1078]: info: Loading plugin "websocket"... Jul 11 09:11:48 volumio volumio[1078]: info: Starting Socket.io Server version 2.3.0 Jul 11 09:11:48 volumio volumio[1078]: info: Loading plugin "80s80s"... Jul 11 09:11:48 volumio volumio[1078]: Forking 3 albumart workers Jul 11 09:11:48 volumio volumio[1078]: info: Applying required configuration parameters for plugin 80s80s Jul 11 09:11:48 volumio volumio[1078]: info: [1752243108711] [80s80s] API delay: 30 Jul 11 09:11:48 volumio volumio[1078]: info: Loading plugin "Systeminfo"... Jul 11 09:11:49 volumio volumio[1078]: Starting albumart workers Jul 11 09:11:49 volumio volumio[1078]: Starting albumart workers Jul 11 09:11:49 volumio volumio[1078]: info: Loading i18n strings for locale en Jul 11 09:11:49 volumio volumio[1078]: Updating browse sources language Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:49 volumio volumio[1078]: Starting albumart workers Jul 11 09:11:49 volumio volumio-remote-updater[620]: [2025-07-11 09:11:49] [connect] Successful connection Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::initPlayerControls Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 09:11:49 volumio volumio[1078]: Express server listening on port 3000 Jul 11 09:11:49 volumio volumio[1078]: [Metrics] WebUI: 18s 590.67ms Jul 11 09:11:49 volumio volumio[1078]: info: CoreStateMachine::resetVolumioState Jul 11 09:11:49 volumio volumio[1078]: info: CoreStateMachine::getcurrentVolume Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::volumioRetrievevolume Jul 11 09:11:50 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 09:11:50 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:50 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:50 volumio sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 09:11:50 volumio sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:50 volumio sudo[1278]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:50 volumio volumio[1078]: info: Volumio Network Manager: Network status updated: 3 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 1 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 3 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 6 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 7 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 8 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 9 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 11 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 12 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 13 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 14 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 15 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 16 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 17 Jul 11 09:11:50 volumio volumio-remote-updater[620]: [2025-07-11 09:11:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752243109 101 Jul 11 09:11:50 volumio volumio[1078]: 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: 18 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 19 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 20 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 21 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 22 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 23 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 24 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 25 Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:11:50 volumio volumio[1078]: info: Reloading queue from file Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 26 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 27 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 28 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 29 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 30 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 31 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 32 Jul 11 09:11:50 volumio volumio[1078]: info: VolumeController:: Volume=100 Mute =false Jul 11 09:11:50 volumio volumio[1078]: info: CoreStateMachine::pushState Jul 11 09:11:50 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::volumioPushState Jul 11 09:11:50 volumio volumio[1078]: info: CoreStateMachine::updateTrackBlock Jul 11 09:11:50 volumio volumio[1078]: info: CorePlayQueue::getTrackBlock Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::volumioRetrievevolume Jul 11 09:11:50 volumio volumio[1078]: info: Setting Device type: Raspberry PI Jul 11 09:11:50 volumio volumio[1078]: info: CoreStateMachine::setRepeat false single undefined Jul 11 09:11:50 volumio volumio[1078]: info: CoreStateMachine::pushState Jul 11 09:11:50 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::volumioPushState Jul 11 09:11:50 volumio volumio[1078]: info: CoreStateMachine::setRandom false Jul 11 09:11:50 volumio volumio[1078]: info: CoreStateMachine::pushState Jul 11 09:11:50 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 09:11:50 volumio volumio[1078]: info: CoreCommandRouter::volumioPushState Jul 11 09:11:50 volumio volumio[1078]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 11 09:11:50 volumio volumio[1078]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jul 11 09:11:50 volumio volumio[1078]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 33 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 34 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 35 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 36 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 37 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 38 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 39 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 40 Jul 11 09:11:50 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 41 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 42 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 43 Jul 11 09:11:51 volumio volumio[1078]: info: Completed loading Core Plugins Jul 11 09:11:51 volumio volumio[1078]: info: Preparing to generate the ALSA configuration file Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 44 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 45 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 46 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 47 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 48 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 49 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 50 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 11 09:11:51 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 51 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 52 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 53 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 54 Jul 11 09:11:51 volumio volumio[1078]: info: Received Get System Info Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 09:11:51 volumio volumio[1078]: info: Discovery: Getting this device information Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetVisibleSources Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 11 09:11:51 volumio volumio[1078]: info: Received Get System Info Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 09:11:51 volumio volumio[1078]: info: Discovery: Getting this device information Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 09:11:51 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:51 volumio volumio[1078]: info: Listing playlists Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetQueue Jul 11 09:11:51 volumio volumio[1078]: info: CoreStateMachine::getQueue Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getQueue Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 55 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 56 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 57 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 58 Jul 11 09:11:51 volumio volumio[1078]: info: Discovery: adding 5419c910-f5d9-43e9-b05e-99cf295721ea Jul 11 09:11:51 volumio volumio[1078]: info: Discovery: Found device Volumio Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 59 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 60 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 61 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 62 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 63 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 64 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 65 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 66 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 67 Jul 11 09:11:51 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 68 Jul 11 09:11:51 volumio volumio[1078]: info: Upmpdcli Daemon Started Jul 11 09:11:51 volumio volumio[1078]: info: VolumeController:: Volume=100 Mute =false Jul 11 09:11:51 volumio volumio[1078]: info: CoreStateMachine::pushState Jul 11 09:11:51 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioPushState Jul 11 09:11:51 volumio volumio[1078]: info: Asound.conf file unchanged, so no further update is needed Jul 11 09:11:51 volumio volumio[1078]: info: Output device has changed, restarting MPD Jul 11 09:11:51 volumio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 09:11:51 volumio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:51 volumio volumio[1078]: info: Output device has changed, restarting Shairport Sync Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:11:51 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:51 volumio sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 09:11:51 volumio sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:51 volumio systemd[1]: Stopping Music Player Daemon... Jul 11 09:11:51 volumio volumio[1078]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 09:11:51 volumio volumio[1078]: info: ___________ START PLUGINS ___________ Jul 11 09:11:51 volumio volumio[1078]: info: ControllerMpd::onStart: Initializing MPD Jul 11 09:11:51 volumio volumio[1078]: info: Creating MPD Configuration file Jul 11 09:11:51 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 09:11:51 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 09:11:51 volumio volumio[1078]: info: [1752243111689] CoreMusicLibrary::Adding element Media Servers Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:51 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:51 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 09:11:51 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:11:51 volumio systemd[1]: mpd.service: Succeeded. Jul 11 09:11:51 volumio systemd[1]: Stopped Music Player Daemon. Jul 11 09:11:51 volumio volumio[1078]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:11:51 volumio volumio[1078]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 09:11:51 volumio volumio[1078]: info: [1752243111864] CoreMusicLibrary::Adding element Last_100 Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 09:11:51 volumio volumio[1078]: info: [1752243111869] CoreMusicLibrary::Adding element Webradio Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 09:11:51 volumio volumio[1078]: info: Initializing BBC Radios Jul 11 09:11:51 volumio systemd[1]: Starting Music Player Daemon... Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:11:51 volumio volumio[1078]: info: Creating Spotify config file Jul 11 09:11:51 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:52 volumio sudo[1328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 09:11:52 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:52 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:52 volumio volumio[1078]: info: [squeezelite_mc] Starting proxy server... Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 09:11:52 volumio volumio[1078]: info: [1752243112158] CoreMusicLibrary::Adding element YouTube Music Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:52 volumio volumio[1078]: Cannot find translation for source YouTube Music Jul 11 09:11:52 volumio volumio[1078]: info: [now-playing] ConfigUpdater: config is up to date. Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 09:11:52 volumio volumio[1078]: info: [1752243112206] CoreMusicLibrary::Adding element 80s80s Radio Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:52 volumio volumio[1078]: Cannot find translation for source YouTube Music Jul 11 09:11:52 volumio volumio[1078]: Cannot find translation for source 80s80s Radio Jul 11 09:11:52 volumio volumio[1078]: info: Volumio Calling Home Jul 11 09:11:52 volumio volumio[1078]: info: [squeezelite_mc] Proxy server started on port 43165 Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::volumioRetrievevolume Jul 11 09:11:52 volumio volumio[1078]: info: [now-playing] App is listening on port 4004. Jul 11 09:11:52 volumio volumio[1078]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 11 09:11:52 volumio volumio[1078]: info: Discovery: this is already registered, 5419c910-f5d9-43e9-b05e-99cf295721ea Jul 11 09:11:52 volumio volumio[1078]: info: Discovery: Found device Volumio Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:52 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 69 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 70 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 71 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 72 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 73 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 74 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 75 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 76 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 77 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 78 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 79 Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 80 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 81 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 82 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 83 Jul 11 09:11:52 volumio volumio[1078]: info: MPD Permissions set Jul 11 09:11:52 volumio volumio[1078]: info: MPD Permissions set Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 84 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 85 Jul 11 09:11:52 volumio volumio[1078]: error: Upnp client error: Error: This socket has been ended by the other party Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 86 Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:52 volumio volumio[1078]: info: Spotify config file written Jul 11 09:11:52 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 11 09:11:52 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 87 Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 88 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 89 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 90 Jul 11 09:11:52 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 91 Jul 11 09:11:52 volumio volumio[1078]: info: VolumeController:: Volume=100 Mute =false Jul 11 09:11:52 volumio volumio[1078]: info: CoreStateMachine::pushState Jul 11 09:11:52 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::volumioPushState Jul 11 09:11:52 volumio volumio[1078]: info: [squeezelite_mc] Server discovery started Jul 11 09:11:52 volumio volumio[1078]: info: [squeezelite_mc] Player finder started Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:52 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 09:11:52 volumio volumio[1078]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 11 09:11:52 volumio systemd[1]: Started go-librespot Daemon. Jul 11 09:11:52 volumio go-librespot[1353]: go-librespot daemon starting... Jul 11 09:11:53 volumio volumio[1078]: info: Volumio called home Jul 11 09:11:53 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio systemd[1]: systemd-timedated.service: Succeeded. Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: No need to fix Spotify hosts Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 92 Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 93 Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 94 Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 95 Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 96 Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 97 Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 98 Jul 11 09:11:53 volumio volumio[1078]: info: [squeezelite_mc] Card 2 supports the following ALSA formats: ["S16_LE","S24_LE","S32_LE"] Jul 11 09:11:53 volumio volumio[1078]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"2","mixerType":"Hardware","mixer":"Digital"} Jul 11 09:11:53 volumio volumio[1078]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 99 Jul 11 09:11:53 volumio go-librespot[1353]: time="2025-07-11T09:11:53-05:00" level=info msg="running go-librespot 0.2.0" Jul 11 09:11:53 volumio go-librespot[1353]: time="2025-07-11T09:11:53-05:00" level=debug msg="app state loaded" Jul 11 09:11:53 volumio go-librespot[1353]: time="2025-07-11T09:11:53-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 11 09:11:53 volumio volumio[1078]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.26 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 100 Jul 11 09:11:53 volumio volumio[1078]: info: Starting Shairport Sync Jul 11 09:11:53 volumio sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 11 09:11:53 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:53 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:53 volumio volumio[1078]: info: Starting Shairport Sync Jul 11 09:11:53 volumio volumio[1078]: info: Starting Shairport Sync Jul 11 09:11:53 volumio sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 09:11:53 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:53 volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 09:11:53 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 09:11:53 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 11 09:11:53 volumio sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 09:11:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 09:11:53 volumio sudo[1382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:11:53 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:11:53 volumio volumio[1078]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 11 09:11:53 volumio volumio[1078]: SPOTIFY: BQBNs5pjmTXbtqWLelWon5a0LrJ8mZYAixPQOD_ZGd88TSqVa3ZIDcy7bIzRiTE--XsYFSIG1BAGtpTN76tGANYmv9vdjLMB4aG9WU56gvqU3pmOy0H6eBal6udLo-Dd00PvVYlCK8KPj4udI_T-lOm-AA8pA-iOh4nLuS4fseeMSbRyy_4DRjGVpMc20aTD_09r52fkSN2nDXnhvkuOyUZ9zbzvdVXqqbf7uBfDC73klNdA_DELV84qFTFinBsm2WLZb69QicVGZ8q7_DbZzC4F Jul 11 09:11:53 volumio volumio[1078]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 11 09:11:53 volumio volumio[1078]: info: New Spotify access token = BQBNs5pjmTXbtqWLelWon5a0LrJ8mZYAixPQOD_ZGd88TSqVa3ZIDcy7bIzRiTE--XsYFSIG1BAGtpTN76tGANYmv9vdjLMB4aG9WU56gvqU3pmOy0H6eBal6udLo-Dd00PvVYlCK8KPj4udI_T-lOm-AA8pA-iOh4nLuS4fseeMSbRyy_4DRjGVpMc20aTD_09r52fkSN2nDXnhvkuOyUZ9zbzvdVXqqbf7uBfDC73klNdA_DELV84qFTFinBsm2WLZb69QicVGZ8q7_DbZzC4F Jul 11 09:11:53 volumio volumio[1078]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 11 09:11:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 09:11:53 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 09:11:53 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 11 09:11:53 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 11 09:11:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 09:11:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 09:11:53 volumio sudo[1382]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:53 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:53 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:54 volumio mpd[1343]: Jul 11 09:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 09:11:54 volumio volumio[1078]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Jul 11 09:11:54 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Jul 11 09:11:54 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=info msg="zeroconf server listening on port 40867" Jul 11 09:11:54 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:54 volumio volumio[1078]: info: Shairport-Sync Started Jul 11 09:11:54 volumio volumio[1078]: Error adding Membership: Error: addMembership EINVAL Jul 11 09:11:54 volumio volumio[1078]: info: Shairport-Sync Started Jul 11 09:11:54 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="obtained new client token: AACFVWYpu9umVGg6Wm+3umBWgus+9FhoVZThF+PBa1h1gpmRvqrQlysuAYX9b9jZmYp9AKKkz7/wXchYcMXhMjqIGCIE353L4AQBZ/cQKA6ipp/2jwI7TX5w2k78CYraKwaCk3dLgxO0ilDgEcuxfC9H0ZzQQ7PC/bJkXQixX3CO07ufA3FAN9Gjo/xTRCbq1STmbv1KYckTjc1p4qrjrwQ4cnfdlHWsb7ng8G2pFGKxyPP6NpUNUj4=" Jul 11 09:11:54 volumio systemd[1]: Started Music Player Daemon. Jul 11 09:11:54 volumio sudo[1313]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:54 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:54 volumio volumio[1078]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="completed keyexchange" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="completed challenge" Jul 11 09:11:54 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 11 09:11:54 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:54 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=info msg="authenticated AP as jtschoen" Jul 11 09:11:54 volumio volumio[1078]: error: MPD error: The expression evaluated to a falsy value: Jul 11 09:11:54 volumio volumio[1078]: assert.ok(self.idling) Jul 11 09:11:54 volumio volumio[1078]: error: The expression evaluated to a falsy value: Jul 11 09:11:54 volumio volumio[1078]: assert.ok(self.idling) Jul 11 09:11:54 volumio volumio[1078]: info: MPD running with PID1343 Jul 11 09:11:54 volumio volumio[1078]: ,establishing connection Jul 11 09:11:54 volumio volumio[1078]: error: updateQueue error: null Jul 11 09:11:54 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=info msg="authenticated Login5 as jtschoen" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="initializing zeroconf session, username: jtschoen" Jul 11 09:11:54 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 11 09:11:54 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:54 volumio systemd[1]: Reloading. Jul 11 09:11:54 volumio volumio[1078]: error: updateQueue error: null Jul 11 09:11:54 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="dealer connection opened" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=trace msg="starting accesspoint recv loop" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=trace msg="starting dealer recv loop" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=trace msg="received accesspoint ping" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=debug msg="received connection id: NDM1OGIzOTQtZTFmNC00NjY2LWIyYTUtOWU5MWE3MzBiYjgyK2RlYWxlcit0Y3A6Ly8wYWIxNDI3NC5pcC5ndWUxLnNwb3RpZnkubmV0OjU3MDArMDQ5QjhDNzcyQTIyQzkxMDIyMkRDNEMwQzVBMTNFMzFCNDQ4N0RDQTI0OEVFNTcxNDkxMEMxQTU3MjNEM0U0OA==" Jul 11 09:11:54 volumio go-librespot[1353]: time="2025-07-11T09:11:54-05:00" level=trace msg="received accesspoint pong ack" Jul 11 09:11:55 volumio volumio[1309]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 11 09:11:55 volumio go-librespot[1353]: time="2025-07-11T09:11:55-05:00" level=debug msg="put connect state because NEW_DEVICE" Jul 11 09:11:55 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 11 09:11:55 volumio volumio[1078]: SPOTIFY: User informations: {"country":"US","display_name":"jtschoen","email":"jtschoen@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/jtschoen"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/jtschoen","id":"jtschoen","images":[],"product":"premium","type":"user","uri":"spotify:user:jtschoen"} Jul 11 09:11:55 volumio volumio[1078]: info: Spotify Successfully logged in Jul 11 09:11:55 volumio volumio[1078]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 09:11:55 volumio volumio[1078]: info: [1752243115198] CoreMusicLibrary::Adding element Spotify Jul 11 09:11:55 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 09:11:55 volumio volumio[1078]: Cannot find translation for source YouTube Music Jul 11 09:11:55 volumio volumio[1078]: Cannot find translation for source 80s80s Radio Jul 11 09:11:55 volumio volumio[1078]: Cannot find translation for source Spotify Jul 11 09:11:55 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:55 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jul 11 09:11:55 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:55 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:55 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 11 09:11:55 volumio sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 11 09:11:55 volumio sudo[1435]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:55 volumio sudo[1435]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:55 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jul 11 09:11:55 volumio sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jul 11 09:11:55 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:56 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jul 11 09:11:56 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jul 11 09:11:56 volumio kernel: process '/opt/squeezelite/squeezelite' started with executable stack Jul 11 09:11:56 volumio sudo[1438]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:56 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:56 volumio volumio[1078]: info: go-librespot daemon successfully initialized Jul 11 09:11:56 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 11 09:11:56 volumio sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 11 09:11:56 volumio sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:56 volumio sudo[1459]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:56 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:57 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 11 09:11:57 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:57 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 11 09:11:57 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:57 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:57 volumio volumio[1309]: .................++++ Jul 11 09:11:57 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:57 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 11 09:11:57 volumio sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 11 09:11:57 volumio sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:57 volumio sudo[1467]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:58 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:58 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 11 09:11:58 volumio sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 11 09:11:58 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:58 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:58 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:58 volumio volumio[1078]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 11 09:11:58 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 11 09:11:58 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:11:58 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Jul 11 09:11:58 volumio volumio[1078]: info: Completed starting Core Plugins Jul 11 09:11:58 volumio volumio[1078]: info: ------------------------------------------- Jul 11 09:11:58 volumio volumio[1078]: info: ----- MyVolumio plugins startup ---- Jul 11 09:11:58 volumio volumio[1078]: info: ------------------------------------------- Jul 11 09:11:59 volumio volumio[1078]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 11 09:11:59 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:59 volumio volumio[1078]: info: Initializing connection to go-librespot Websocket Jul 11 09:11:59 volumio go-librespot[1353]: time="2025-07-11T09:11:59-05:00" level=debug msg="new websocket client" Jul 11 09:11:59 volumio volumio[1078]: info: Connection to go-librespot Websocket established Jul 11 09:11:59 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:11:59 volumio volumio[1078]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 11 09:12:00 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:12:01 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 11 09:12:01 volumio volumio[1309]: ........................++++ Jul 11 09:12:01 volumio volumio[1309]: e is 65537 (0x010001) Jul 11 09:12:01 volumio volumio[1309]: writing RSA key Jul 11 09:12:02 volumio volumio[1078]: Cannot compose Albumart path Jul 11 09:12:02 volumio volumio[1078]: info: Getting Spotify volume Jul 11 09:12:02 volumio volumio[1078]: info: Spotify volume: 100 Jul 11 09:12:02 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 101 Jul 11 09:12:02 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:12:02 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:12:02 volumio volumio[1078]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 11 09:12:07 volumio volumio[1078]: info: Adding plugin bluetooth to MyMusic Plugins Jul 11 09:12:07 volumio volumio[1078]: info: Adding plugin multiroom to MyMusic Plugins Jul 11 09:12:07 volumio volumio[1078]: info: Adding plugin metavolumio to MyMusic Plugins Jul 11 09:12:07 volumio volumio[1078]: info: Adding plugin cd_controller to MyMusic Plugins Jul 11 09:12:07 volumio volumio[1078]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 11 09:12:07 volumio volumio[1078]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 11 09:12:07 volumio volumio[1078]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 11 09:12:07 volumio volumio[1078]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 11 09:12:08 volumio volumio[1078]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 11 09:12:08 volumio volumio[1078]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 11 09:12:08 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:08 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:08 volumio volumio[1078]: info: Starting MyVolumio Remote Streaming Endpoints Jul 11 09:12:08 volumio volumio[1078]: info: MyVolumio login type: Token Jul 11 09:12:08 volumio volumio[1078]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 11 09:12:08 volumio volumio[1078]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 11 09:12:10 volumio volumio[1078]: info: Starting Streaming Service Transparent Proxy Jul 11 09:12:10 volumio volumio[1078]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 11 09:12:10 volumio volumio[1078]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 11 09:12:10 volumio volumio[1078]: info: Streaming services startup Jul 11 09:12:10 volumio volumio[1078]: info: Starting Streaming Daemon Jul 11 09:12:10 volumio sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 11 09:12:10 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:12:10 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Jul 11 09:12:10 volumio volumio[1078]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 11 09:12:10 volumio volumio[1078]: error: Cannot start Volumio Streaming Daemon Jul 11 09:12:10 volumio volumio[1078]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 11 09:12:10 volumio volumio[1078]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 11 09:12:10 volumio volumio[1078]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 11 09:12:11 volumio volumio[1078]: info: MyVolumio token set successfully Jul 11 09:12:11 volumio volumio[1078]: info: MYVOLUMIO: Adding device Jul 11 09:12:11 volumio volumio[1078]: info: MYVOLUMIO: Evaluating Server Jul 11 09:12:11 volumio volumio[1078]: STREAMING PROXY: Starting server on port 3245 Jul 11 09:12:11 volumio volumio[1078]: Node JS runtime: 14 Jul 11 09:12:12 volumio volumio[1078]: info: MyVolumio status changed Jul 11 09:12:12 volumio volumio[1078]: info: Streaming services startup Jul 11 09:12:12 volumio volumio[1078]: info: Starting Streaming Daemon Jul 11 09:12:12 volumio volumio[1078]: info: Removing browser output: myVolumio user plan is not superstar Jul 11 09:12:12 volumio volumio[1078]: info: Removing audio output: Jul 11 09:12:12 volumio volumio[1078]: info: Stoppping Tunnel 1 Jul 11 09:12:12 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 11 09:12:12 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:12:12 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Jul 11 09:12:12 volumio sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 11 09:12:12 volumio sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 09:12:12 volumio volumio[1078]: error: Cannot start Volumio Streaming Daemon Jul 11 09:12:12 volumio volumio[1078]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 11 09:12:12 volumio volumio[1078]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 11 09:12:12 volumio sudo[1580]: pam_unix(sudo:session): session closed for user root Jul 11 09:12:12 volumio volumio[1078]: info: Remote SSH Stopped Jul 11 09:12:12 volumio volumio[1078]: info: Setting Geolocation for MyVolumio to us3 Jul 11 09:12:12 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:12 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:12 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:12 volumio volumio[1078]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 11 09:12:13 volumio volumio[1078]: info: Updating MyVolumio device info Jul 11 09:12:13 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:14 volumio volumio[1078]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 11 09:12:16 volumio volumio[1078]: info: MYVOLUMIO: Adding device Jul 11 09:12:16 volumio volumio[1078]: info: MYVOLUMIO: Evaluating Server Jul 11 09:12:17 volumio volumio[1078]: info: Setting Geolocation for MyVolumio to us3 Jul 11 09:12:17 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:17 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:17 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:18 volumio volumio[1078]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 11 09:12:18 volumio volumio[1078]: info: Updating MyVolumio device info Jul 11 09:12:18 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:18 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:18 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:19 volumio ntpd[785]: Soliciting pool server 198.137.202.32 Jul 11 09:12:19 volumio ntpd[785]: Soliciting pool server 185.122.164.153 Jul 11 09:12:19 volumio volumio[1078]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 11 09:12:20 volumio ntpd[785]: Soliciting pool server 204.2.134.173 Jul 11 09:12:20 volumio ntpd[785]: Soliciting pool server 157.245.125.229 Jul 11 09:12:21 volumio ntpd[785]: Soliciting pool server 66.42.71.197 Jul 11 09:12:21 volumio ntpd[785]: Soliciting pool server 141.11.228.173 Jul 11 09:12:22 volumio ntpd[785]: Soliciting pool server 23.186.168.132 Jul 11 09:12:22 volumio ntpd[785]: Soliciting pool server 66.118.230.14 Jul 11 09:12:22 volumio ntpd[785]: Soliciting pool server 209.94.190.139 Jul 11 09:12:22 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 09:12:22 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:22 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 11 09:12:22 volumio volumio-remote-updater[620]: No test mode Jul 11 09:12:22 volumio volumio-remote-updater[620]: No alpha test mode Jul 11 09:12:22 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 11 09:12:22 volumio volumio[1078]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Disable UI controls during DLNA playback
    • \n
  • Fix for Cast functionality halting due to memory filling up
    • \n
  • Fix for XML parsing on web-radio search
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add link for downloading CORRD in the side-menu
    • \n
\n","title":"Update v3.819","updateavailable":true} Jul 11 09:12:22 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 11 09:12:23 volumio ntpd[785]: Soliciting pool server 137.110.222.27 Jul 11 09:12:23 volumio ntpd[785]: Soliciting pool server 23.150.41.123 Jul 11 09:12:23 volumio ntpd[785]: Soliciting pool server 198.60.22.240 Jul 11 09:12:23 volumio ntpd[785]: Soliciting pool server 216.31.17.12 Jul 11 09:12:23 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 11 09:12:23 volumio volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 11 09:12:23 volumio volumio[1078]: info: CoreCommandRouter::volumioGetState Jul 11 09:12:23 volumio volumio[1078]: info: CorePlayQueue::getTrack 0 Jul 11 09:12:24 volumio ntpd[785]: Soliciting pool server 104.234.61.117 Jul 11 09:12:24 volumio ntpd[785]: Soliciting pool server 50.251.160.20 Jul 11 09:12:24 volumio ntpd[785]: Soliciting pool server 162.159.200.123 Jul 11 09:12:24 volumio go-librespot[1353]: time="2025-07-11T09:12:24-05:00" level=trace msg="sent dealer ping" Jul 11 09:12:24 volumio go-librespot[1353]: time="2025-07-11T09:12:24-05:00" level=trace msg="received dealer pong" Jul 11 09:12:25 volumio ntpd[785]: Soliciting pool server 23.186.168.131 Jul 11 09:12:25 volumio ntpd[785]: Soliciting pool server 4.7.252.53 Jul 11 09:12:25 volumio ntpd[785]: Soliciting pool server 83.147.242.172 Jul 11 09:12:26 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 11 09:12:26 volumio volumio[1078]: info: CURURI: albums:// Jul 11 09:12:26 volumio volumio[1078]: info: listAlbums - loading Albums from cache Jul 11 09:12:26 volumio volumio[1078]: info: Preload queue cleared Jul 11 09:12:28 volumio ntpd[785]: receive: Unexpected origin timestamp 0xec1b9a4b.2506b6c2 does not match aorg 0000000000.00000000 from server@204.2.134.173 xmt 0xec1b9a4c.10b06f34 Jul 11 09:12:28 volumio ntpd[785]: receive: Unexpected origin timestamp 0xec1b9a4b.24fdc99c does not match aorg 0000000000.00000000 from server@104.234.61.117 xmt 0xec1b9a4c.10be00ea Jul 11 09:12:28 volumio ntpd[785]: receive: Unexpected origin timestamp 0xec1b9a4b.24f9caf3 does not match aorg 0000000000.00000000 from server@83.147.242.172 xmt 0xec1b9a4c.11acd7d8 Jul 11 09:12:28 volumio ntpd[785]: receive: Unexpected origin timestamp 0xec1b9a4b.25025afb does not match aorg 0000000000.00000000 from server@137.110.222.27 xmt 0xec1b9a4c.11ac16c4 Jul 11 09:12:28 volumio ntpd[785]: receive: Unexpected origin timestamp 0xec1b9a4b.25032e43 does not match aorg 0000000000.00000000 from server@66.118.230.14 xmt 0xec1b9a4c.1f41d112 Jul 11 09:12:29 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:12:29 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 11 09:12:29 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 11 09:12:29 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 11 09:12:29 volumio volumio[1078]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 09:12:29 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 09:12:31 volumio volumio[1078]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 11 09:12:31 volumio volumio[1078]: info: Completed starting MyVolumio Plugin Jul 11 09:12:31 volumio volumio[1078]: [Metrics] CommandRouter: 58s 154.08ms Jul 11 09:12:31 volumio volumio[1078]: info: CoreCommandRouter::volumiosetStartupVolume Jul 11 09:12:31 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 09:12:31 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 09:12:31 volumio volumio[1078]: info: CoreCommandRouter::Close All Modals sent Jul 11 09:12:31 volumio volumio[1078]: info: CoreCommandRouter::Close All Modals sent Jul 11 09:12:32 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 11 09:12:32 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 11 09:12:32 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 11 09:12:38 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 09:12:38 volumio volumio[1078]: info: Preload queue cleared Jul 11 09:12:38 volumio volumio[1078]: info: BOOT COMPLETED Jul 11 09:12:39 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 09:12:39 volumio volumio[1078]: info: Preload queue cleared Jul 11 09:12:41 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 09:12:42 volumio volumio[1078]: info: Preload queue cleared Jul 11 09:12:42 volumio volumio[1078]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jul 11 09:12:42 volumio volumio[1078]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jul 11 09:12:45 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 09:12:45 volumio volumio[1078]: info: Preload queue cleared Jul 11 09:12:47 volumio volumio[1078]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 11 09:12:47 volumio volumio[1078]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 09:12:47 volumio volumio[1078]: TypeError: Cannot read property 'length' of undefined Jul 11 09:12:47 volumio volumio[1078]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 11 09:12:47 volumio volumio[1078]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 11 09:12:47 volumio volumio[1078]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 11 09:12:47 volumio volumio[1078]: at Parser.emit (events.js:315:20) Jul 11 09:12:47 volumio volumio[1078]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 11 09:12:47 volumio volumio[1078]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 11 09:12:47 volumio volumio[1078]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 11 09:12:47 volumio volumio[1078]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 11 09:12:47 volumio volumio[1078]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 11 09:12:47 volumio volumio[1078]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 11 09:12:47 volumio volumio[1078]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 11 09:12:47 volumio volumio[1078]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 11 09:12:47 volumio volumio[1078]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 11 09:12:47 volumio volumio[1078]: at IncomingMessage.emit (events.js:327:22) Jul 11 09:12:47 volumio volumio[1078]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 11 09:12:47 volumio volumio[1078]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 11 09:12:47 volumio volumio[1078]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 09:12:48 volumio sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-11 09:11 Jul 11 09:12:48 volumio sudo[1673]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"