-- Logs begin at Tue 2025-06-10 18:21:52 CEST, end at Tue 2025-06-10 18:23:57 CEST. -- Jun 10 18:22:00 volumio.local.local wireless.js[574]: trying... Jun 10 18:22:00 volumio.local.local sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 10 18:22:00 volumio.local.local sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:00 volumio.local.local sudo[1005]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:00 volumio.local.local ntpd[878]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 10 18:22:01 volumio.local.local wireless.js[574]: trying... Jun 10 18:22:01 volumio.local.local sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 10 18:22:01 volumio.local.local sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:01 volumio.local.local sudo[1015]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:02 volumio.local.local wireless.js[574]: trying... Jun 10 18:22:02 volumio.local.local sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 10 18:22:02 volumio.local.local sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:02 volumio.local.local sudo[1025]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:02 volumio.local.local sh[508]: eth0: leased 192.168.178.79 for 864000 seconds Jun 10 18:22:02 volumio.local.local sh[508]: eth0: adding route to 192.168.178.0/24 Jun 10 18:22:02 volumio.local.local sh[508]: eth0: adding default route via 192.168.178.1 Jun 10 18:22:02 volumio.local.local dhcpcd[542]: eth0: leased 192.168.178.79 for 864000 seconds Jun 10 18:22:02 volumio.local.local avahi-daemon[579]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.79. Jun 10 18:22:02 volumio.local.local avahi-daemon[579]: New relevant interface eth0.IPv4 for mDNS. Jun 10 18:22:02 volumio.local.local dhcpcd[542]: eth0: adding route to 192.168.178.0/24 Jun 10 18:22:02 volumio.local.local dhcpcd[542]: eth0: adding default route via 192.168.178.1 Jun 10 18:22:02 volumio.local.local avahi-daemon[579]: Registering new address record for 192.168.178.79 on eth0.IPv4. Jun 10 18:22:03 volumio.local.local ntpd[878]: ntpd exiting on signal 15 (Terminated) Jun 10 18:22:03 volumio.local.local systemd[1]: Stopping Network Time Service... Jun 10 18:22:03 volumio.local.local wireless.js[574]: trying... Jun 10 18:22:03 volumio.local.local sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 10 18:22:03 volumio.local.local sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:03 volumio.local.local sudo[1070]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:03 volumio.local.local systemd[1]: ntp.service: Succeeded. Jun 10 18:22:03 volumio.local.local systemd[1]: Stopped Network Time Service. Jun 10 18:22:03 volumio.local.local systemd[1]: Starting Network Time Service... Jun 10 18:22:03 volumio.local.local ntpd[1081]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 10 18:22:03 volumio.local.local ntpd[1081]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Jun 10 18:22:03 volumio.local.local systemd[1]: Started Network Time Service. Jun 10 18:22:03 volumio.local.local ntpd[1087]: proto: precision = 1.166 usec (-20) Jun 10 18:22:03 volumio.local.local ntpd[1087]: Listen and drop on 0 v6wildcard [::]:123 Jun 10 18:22:03 volumio.local.local sh[508]: forked to background, child pid 1088 Jun 10 18:22:03 volumio.local.local ntpd[1087]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 10 18:22:03 volumio.local.local ntpd[1087]: Listen normally on 2 lo 127.0.0.1:123 Jun 10 18:22:03 volumio.local.local ntpd[1087]: Listen normally on 3 eth0 192.168.178.79:123 Jun 10 18:22:03 volumio.local.local ntpd[1087]: Listening on routing socket on fd #20 for interface updates Jun 10 18:22:03 volumio.local.local dhcpcd[542]: forked to background, child pid 1088 Jun 10 18:22:03 volumio.local.local ntpd[1087]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 10 18:22:03 volumio.local.local ntpd[1087]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 10 18:22:03 volumio.local.local ifplugd(eth0)[895]: client: ifup: interface eth0 already configured Jun 10 18:22:03 volumio.local.local sh[508]: eth0=eth0 Jun 10 18:22:03 volumio.local.local ifplugd(eth0)[895]: Program executed successfully. Jun 10 18:22:03 volumio.local.local ifplugd[843]: Network Interface Plugging Daemon...start eth0...done. Jun 10 18:22:03 volumio.local.local systemd[1]: Started LSB: Brings up/down network automatically. Jun 10 18:22:03 volumio.local.local dhcpcd[580]: eth0: leased 192.168.178.79 for 864000 seconds Jun 10 18:22:03 volumio.local.local dhcpcd[580]: eth0: adding route to 192.168.178.0/24 Jun 10 18:22:03 volumio.local.local dhcpcd[580]: eth0: adding default route via 192.168.178.1 Jun 10 18:22:03 volumio.local.local dhcpcd[580]: eth0: leased 192.168.178.79 for 864000 seconds Jun 10 18:22:03 volumio.local.local dhcpcd[580]: eth0: adding route to 192.168.178.0/24 Jun 10 18:22:03 volumio.local.local dhcpcd[580]: eth0: adding default route via 192.168.178.1 Jun 10 18:22:03 volumio.local.local ntpd[1087]: ntpd exiting on signal 15 (Terminated) Jun 10 18:22:03 volumio.local.local ntpd[1087]: 192.168.178.1 local addr 192.168.178.79 -> Jun 10 18:22:03 volumio.local.local systemd[1]: Stopping Network Time Service... Jun 10 18:22:03 volumio.local.local systemd[1]: ntp.service: Succeeded. Jun 10 18:22:03 volumio.local.local systemd[1]: Stopped Network Time Service. Jun 10 18:22:03 volumio.local.local systemd[1]: Starting Network Time Service... Jun 10 18:22:03 volumio.local.local ntpd[1154]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 10 18:22:03 volumio.local.local ntpd[1154]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Jun 10 18:22:03 volumio.local.local systemd[1]: Started Network Time Service. Jun 10 18:22:03 volumio.local.local ntpd[1160]: proto: precision = 0.875 usec (-20) Jun 10 18:22:03 volumio.local.local ntpd[1160]: Listen and drop on 0 v6wildcard [::]:123 Jun 10 18:22:03 volumio.local.local ntpd[1160]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 10 18:22:03 volumio.local.local ntpd[1160]: Listen normally on 2 lo 127.0.0.1:123 Jun 10 18:22:03 volumio.local.local ntpd[1160]: Listen normally on 3 eth0 192.168.178.79:123 Jun 10 18:22:03 volumio.local.local ntpd[1160]: Listening on routing socket on fd #20 for interface updates Jun 10 18:22:03 volumio.local.local ntpd[1160]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 10 18:22:03 volumio.local.local ntpd[1160]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 10 18:22:03 volumio.local.local dhcpcd[580]: forked to background, child pid 1161 Jun 10 18:22:03 volumio.local.local dhcpcd[580]: forked to background, child pid 1161 Jun 10 18:22:03 volumio.local.local systemd[1]: Started DHCP Client Daemon. Jun 10 18:22:04 volumio.local.local wireless.js[574]: trying... Jun 10 18:22:04 volumio.local.local sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 10 18:22:04 volumio.local.local sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:04 volumio.local.local volumio-time-update[558]: volumio-time-update-util: Fetching time from Volumio... Jun 10 18:22:04 volumio.local.local sudo[1164]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:04 volumio.local.local volumio-time-update[558]: volumio-time-update-util: Setting system time to: 2025-06-10 16:22:03 Jun 10 18:22:04 volumio.local.local sudo[1178]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-10 16:22:03 Jun 10 18:22:04 volumio.local.local sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:04 volumio.local.local dbus-daemon[571]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.7' (uid=0 pid=1183 comm="timedatectl set-time 2025-06-10 16:22:03 ") Jun 10 18:22:04 volumio.local.local systemd[1]: Starting Time & Date Service... Jun 10 18:22:04 volumio.local.local dhcpcd[1161]: wlan0: leased 192.168.178.57 for 864000 seconds Jun 10 18:22:04 volumio.local.local dhcpcd[1161]: wlan0: adding route to 192.168.178.0/24 Jun 10 18:22:04 volumio.local.local dhcpcd[1161]: wlan0: adding default route via 192.168.178.1 Jun 10 18:22:04 volumio.local.local avahi-daemon[579]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.57. Jun 10 18:22:04 volumio.local.local avahi-daemon[579]: New relevant interface wlan0.IPv4 for mDNS. Jun 10 18:22:04 volumio.local.local avahi-daemon[579]: Registering new address record for 192.168.178.57 on wlan0.IPv4. Jun 10 18:22:04 volumio.local.local systemd-timedated[1184]: /etc/localtime should be a symbolic link to a time zone data file in /usr/share/zoneinfo/. Jun 10 18:22:04 volumio.local.local dbus-daemon[571]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 10 18:22:04 volumio.local.local systemd[1]: Started Time & Date Service. Jun 10 18:22:03 volumio.local.local systemd-timedated[1184]: Changed local time to Tue Jun 10 16:22:03 2025 Jun 10 18:22:03 volumio.local.local sudo[1178]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:03 volumio.local.local volumio-time-update[558]: volumio-time-update-util: System time updated successfully. Jun 10 18:22:03 volumio.local.local systemd[1]: Started Volumio Time Update Utility. Jun 10 18:22:03 volumio.local.local ntpd[1160]: ntpd exiting on signal 15 (Terminated) Jun 10 18:22:03 volumio.local.local ntpd[1160]: 192.168.178.1 local addr 192.168.178.79 -> Jun 10 18:22:03 volumio.local.local systemd[1]: Stopping Network Time Service... Jun 10 18:22:03 volumio.local.local systemd[1]: ntp.service: Succeeded. Jun 10 18:22:03 volumio.local.local systemd[1]: Stopped Network Time Service. Jun 10 18:22:03 volumio.local.local systemd[1]: Starting Network Time Service... Jun 10 18:22:03 volumio.local.local ntpd[1229]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 10 18:22:03 volumio.local.local ntpd[1229]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Jun 10 18:22:03 volumio.local.local systemd[1]: Started Network Time Service. Jun 10 18:22:03 volumio.local.local ntpd[1235]: proto: precision = 0.875 usec (-20) Jun 10 18:22:03 volumio.local.local ntpd[1235]: Listen and drop on 0 v6wildcard [::]:123 Jun 10 18:22:03 volumio.local.local ntpd[1235]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 10 18:22:03 volumio.local.local ntpd[1235]: Listen normally on 2 lo 127.0.0.1:123 Jun 10 18:22:03 volumio.local.local ntpd[1235]: Listen normally on 3 eth0 192.168.178.79:123 Jun 10 18:22:03 volumio.local.local ntpd[1235]: Listen normally on 4 wlan0 192.168.178.57:123 Jun 10 18:22:03 volumio.local.local ntpd[1235]: Listening on routing socket on fd #21 for interface updates Jun 10 18:22:03 volumio.local.local ntpd[1235]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 10 18:22:03 volumio.local.local ntpd[1235]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 10 18:22:03 volumio.local.local wireless.js[574]: trying... Jun 10 18:22:03 volumio.local.local sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 10 18:22:03 volumio.local.local sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:03 volumio.local.local sudo[1238]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:03 volumio.local.local wireless.js[574]: Connected to: ----FRITZ!Box 7490 Jun 10 18:22:03 volumio.local.local wireless.js[574]: ---- Jun 10 18:22:03 volumio.local.local sudo[1248]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 18:22:03 volumio.local.local sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:03 volumio.local.local sudo[1248]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:03 volumio.local.local wireless.js[574]: ... joined AP, wlan0 IPv4 is 192.168.178.57, ipV6 is undefined Jun 10 18:22:03 volumio.local.local wireless.js[574]: It's done! AP Jun 10 18:22:03 volumio.local.local iwconfig[1259]: Error for wireless request "Set Power Management" (8B2C) : Jun 10 18:22:03 volumio.local.local iwconfig[1259]: SET failed on device wlan0 ; Operation not permitted. Jun 10 18:22:03 volumio.local.local systemd[1]: Started Wireless Services. Jun 10 18:22:03 volumio.local.local systemd[1]: Started Volumio Backend Module. Jun 10 18:22:03 volumio.local.local systemd[1]: Started Volumio Cpu Tweaker. Jun 10 18:22:03 volumio.local.local volumio-cpu-tweak[1261]: Setting RT Priority for mpd Jun 10 18:22:03 volumio.local.local volumio-cpu-tweak[1261]: Setting MPD Affinity Jun 10 18:22:03 volumio.local.local volumio-cpu-tweak[1261]: pid 896's current affinity mask: f Jun 10 18:22:03 volumio.local.local volumio-cpu-tweak[1261]: pid 896's new affinity mask: 3 Jun 10 18:22:03 volumio.local.local volumio-cpu-tweak[1261]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 10 18:22:03 volumio.local.local volumio-cpu-tweak[1261]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 10 18:22:03 volumio.local.local systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 10 18:22:05 volumio.local.local nmbd[850]: [2025/06/10 16:22:05.215877, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 10 18:22:05 volumio.local.local nmbd[850]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 10 18:22:05 volumio.local.local systemd[1]: Started Samba NMB Daemon. Jun 10 18:22:05 volumio.local.local systemd[1]: Starting Samba Winbind Daemon... Jun 10 18:22:05 volumio.local.local kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. Jun 10 18:22:05 volumio.local.local volumio[1260]: info: ------------------------------------------- Jun 10 18:22:05 volumio.local.local volumio[1260]: info: ----- Volumio3 ---- Jun 10 18:22:05 volumio.local.local volumio[1260]: info: ------------------------------------------- Jun 10 18:22:05 volumio.local.local volumio[1260]: info: ----- System startup ---- Jun 10 18:22:05 volumio.local.local volumio[1260]: info: ------------------------------------------- Jun 10 18:22:05 volumio.local.local winbindd[1285]: [2025/06/10 16:22:05.429367, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 10 18:22:05 volumio.local.local winbindd[1285]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 10 18:22:05 volumio.local.local winbindd[1285]: [2025/06/10 16:22:05.438872, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 10 18:22:05 volumio.local.local winbindd[1285]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 10 18:22:05 volumio.local.local systemd[1]: Started Samba Winbind Daemon. Jun 10 18:22:05 volumio.local.local systemd[1]: Starting Samba SMB Daemon... Jun 10 18:22:05 volumio.local.local systemd[1]: Started Samba SMB Daemon. Jun 10 18:22:05 volumio.local.local systemd[1]: Reached target Multi-User System. Jun 10 18:22:05 volumio.local.local systemd[1]: Reached target Graphical Interface. Jun 10 18:22:05 volumio.local.local systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 10 18:22:05 volumio.local.local smbd[1290]: [2025/06/10 16:22:05.802754, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 10 18:22:05 volumio.local.local smbd[1290]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 10 18:22:05 volumio.local.local systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 10 18:22:05 volumio.local.local systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 10 18:22:05 volumio.local.local systemd[1]: Startup finished in 20.898s (kernel) + 15.664s (userspace) = 36.562s. Jun 10 18:22:06 volumio.local.local volumio[1260]: info: MYVOLUMIO Environment detected Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Plugin folders cleanup Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning into folder /volumio/app/plugins/ Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category audio_interface Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category miscellanea Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category music_service Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category plugins.json Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category system_controller Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category user_interface Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning into folder /data/plugins/ Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category music_service Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Scanning category user_interface Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Plugin folders cleanup completed Jun 10 18:22:06 volumio.local.local volumio[1260]: info: ------------------------------------------- Jun 10 18:22:06 volumio.local.local volumio[1260]: info: ----- Core plugins startup ---- Jun 10 18:22:06 volumio.local.local volumio[1260]: info: ------------------------------------------- Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Adding plugin upnp to MyMusic Plugins Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Loading plugins from folder /data/plugins/ Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Loading plugin "system"... Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Loading plugin "appearance"... Jun 10 18:22:06 volumio.local.local volumio[1260]: info: Loading plugin "network"... Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Refreshing Cached IP Addresses Jun 10 18:22:07 volumio.local.local sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Loading plugin "services"... Jun 10 18:22:07 volumio.local.local sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:07 volumio.local.local sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Loading plugin "alsa_controller"... Jun 10 18:22:07 volumio.local.local sudo[1306]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:07 volumio.local.local sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:07 volumio.local.local sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 18:22:07 volumio.local.local sudo[1308]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:07 volumio.local.local sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:07 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Loading plugin "wizard"... Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Loading plugin "networkfs"... Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Starting Udev Watcher for removable devices Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Ignoring mount for partition: BOOT Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Ignoring mount for partition: volumio Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Ignoring mount for partition: volumio_data Jun 10 18:22:07 volumio.local.local volumio[1260]: info: Mounting Device Volume Jun 10 18:22:07 volumio.local.local sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Volume -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 10 18:22:07 volumio.local.local sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:07 volumio.local.local sudo[1325]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:07 volumio.local.local volumio-remote-updater[565]: [2025-06-10 16:22:07] [connect] Successful connection Jun 10 18:22:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 Jun 10 18:22:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Jun 10 18:22:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Jun 10 18:22:08 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0a 08 00 00 f0 00 Jun 10 18:22:08 volumio.local.local kernel: blk_update_request: critical medium error, dev sda, sector 2568 Jun 10 18:22:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 Jun 10 18:22:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Jun 10 18:22:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Jun 10 18:22:10 volumio.local.local kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 0a e0 00 00 08 00 Jun 10 18:22:10 volumio.local.local kernel: blk_update_request: critical medium error, dev sda, sector 2784 Jun 10 18:22:10 volumio.local.local kernel: Buffer I/O error on dev sda1, logical block 92, async page read Jun 10 18:22:11 volumio.local.local ntfs-3g[1370]: Version 2017.3.23AR.3 integrated FUSE 28 Jun 10 18:22:11 volumio.local.local sudo[1360]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:11 volumio.local.local ntfs-3g[1370]: Mounted /dev/sda1 (Read-Write, label "Volume", NTFS 3.1) Jun 10 18:22:11 volumio.local.local ntfs-3g[1370]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 10 18:22:11 volumio.local.local ntfs-3g[1370]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Jun 10 18:22:11 volumio.local.local ntfs-3g[1370]: Global ownership and permissions enforced, configuration type 7 Jun 10 18:22:11 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 18:22:11 volumio.local.local volumio[1260]: info: Loading plugin "volumio_command_line_client"... Jun 10 18:22:11 volumio.local.local volumio[1260]: info: Loading plugin "upnp"... Jun 10 18:22:11 volumio.local.local volumio[1260]: info: [1749572531414] Starting Upmpd Daemon Jun 10 18:22:11 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 18:22:11 volumio.local.local volumio[1260]: info: Loading plugin "my_music"... Jun 10 18:22:11 volumio.local.local volumio[1260]: info: Loading plugin "mpd"... Jun 10 18:22:11 volumio.local.local volumio[1260]: info: Loading plugin "upnp_browser"... Jun 10 18:22:11 volumio.local.local volumio[1260]: info: Loading plugin "alarm-clock"... Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Loading plugin "airplay_emulation"... Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Starting Shairport Sync Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Loading plugin "last_100"... Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Loading plugin "webradio"... Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Loading plugin "i2s_dacs"... Jun 10 18:22:12 volumio.local.local volumio[1260]: info: I2S DAC not set, start Auto-detection Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Loading plugin "volumiodiscovery"... Jun 10 18:22:12 volumio.local.local volumio[1260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 18:22:12 volumio.local.local volumio[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 18:22:12 volumio.local.local volumio[1260]: *** WARNING *** For more information see Jun 10 18:22:12 volumio.local.local volumio[1260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 18:22:12 volumio.local.local volumio[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 18:22:12 volumio.local.local node[1260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 18:22:12 volumio.local.local volumio[1260]: *** WARNING *** For more information see Jun 10 18:22:12 volumio.local.local node[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 18:22:12 volumio.local.local node[1260]: *** WARNING *** For more information see Jun 10 18:22:12 volumio.local.local node[1260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 18:22:12 volumio.local.local node[1260]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 18:22:12 volumio.local.local node[1260]: *** WARNING *** For more information see Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Discovery: Started advertising with name: Volumio.local.local Jun 10 18:22:12 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 18:22:12 volumio.local.local volumio[1260]: info: Loading plugin "youtube2"... Jun 10 18:22:14 volumio.local.local volumio[1260]: info: Loading plugin "ytcr"... Jun 10 18:22:16 volumio.local.local volumio[1260]: info: Loading plugin "ytmusic"... Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Loading plugin "outputs"... Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Loading plugin "albumart"... Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Plugin example_plugin is not enabled Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Loading plugin "inputs"... Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Loading plugin "updater_comm"... Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Plugin mpdemulation is not enabled Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Loading plugin "rest_api"... Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Loading plugin "websocket"... Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Starting Socket.io Server version 2.3.0 Jun 10 18:22:17 volumio.local.local volumio[1260]: info: Loading plugin "80s80s"... Jun 10 18:22:17 volumio.local.local volumio[1260]: Forking 3 albumart workers Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Applying required configuration parameters for plugin 80s80s Jun 10 18:22:18 volumio.local.local volumio[1260]: info: [1749572538185] [80s80s] API delay: 30 Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Loading plugin "RoonBridge"... Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Applying required configuration parameters for plugin RoonBridge Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Loading plugin "radio_paradise"... Jun 10 18:22:18 volumio.local.local volumio[1260]: Starting albumart workers Jun 10 18:22:18 volumio.local.local volumio[1260]: Starting albumart workers Jun 10 18:22:18 volumio.local.local volumio[1260]: Starting albumart workers Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 18:22:18 volumio.local.local volumio[1260]: info: [1749572538699] [RadioParadise] API delay: 5 Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Plugin randomizer is not enabled Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Loading i18n strings for locale de Jun 10 18:22:18 volumio.local.local volumio[1260]: Updating browse sources language Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::initPlayerControls Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: Express server listening on port 3000 Jun 10 18:22:18 volumio.local.local volumio[1260]: [Metrics] WebUI: 13s 519.27ms Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreStateMachine::resetVolumioState Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreStateMachine::getcurrentVolume Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreStateMachine::pushState Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushState Jun 10 18:22:18 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:18 volumio.local.local volumio[1260]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Completed loading Core Plugins Jun 10 18:22:18 volumio.local.local volumio[1260]: info: Preparing to generate the ALSA configuration file Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Volumio Network Manager: Network status updated: 3 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Reading ALSA contributions from plugins. Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 1 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 4 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 9 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 10 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 11 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 12 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 13 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 14 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 15 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 16 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 17 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 18 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 19 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 20 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 21 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 22 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 23 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 24 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 25 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 26 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 27 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 28 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 29 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 30 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 31 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 32 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 33 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 34 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 35 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 36 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Reloading queue from file Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 37 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 38 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 39 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 40 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 41 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 42 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreStateMachine::setRepeat true single undefined Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreStateMachine::pushState Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushState Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreStateMachine::setRandom false Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreStateMachine::pushState Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushState Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Setting Device type: Tinkerboard Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 43 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 44 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 45 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 46 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 47 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 48 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 49 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Asound.conf file unchanged, so no further update is needed Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Output device has changed, restarting MPD Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Output device has changed, restarting Shairport Sync Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:19 volumio.local.local sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 18:22:19 volumio.local.local sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:19 volumio.local.local sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 18:22:19 volumio.local.local sudo[1457]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:19 volumio.local.local sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:19 volumio.local.local volumio[1260]: info: ___________ START PLUGINS ___________ Jun 10 18:22:19 volumio.local.local volumio[1260]: info: ControllerMpd::onStart: Initializing MPD Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Creating MPD Configuration file Jun 10 18:22:19 volumio.local.local systemd[1]: Stopping Music Player Daemon... Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:19 volumio.local.local volumio[1260]: info: [1749572539404] CoreMusicLibrary::Adding element Medienserver Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:19 volumio.local.local sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:19 volumio.local.local sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 18:22:19 volumio.local.local sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:19 volumio.local.local sudo[1477]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:19 volumio.local.local sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:19 volumio.local.local volumio[1260]: info: [1749572539504] CoreMusicLibrary::Adding element Last_100 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:19 volumio.local.local volumio[1260]: info: [1749572539506] CoreMusicLibrary::Adding element Webradio Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Initializing BBC Radios Jun 10 18:22:19 volumio.local.local systemd[1]: mpd.service: Succeeded. Jun 10 18:22:19 volumio.local.local systemd[1]: Stopped Music Player Daemon. Jun 10 18:22:19 volumio.local.local systemd[1]: Starting Music Player Daemon... Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:19 volumio.local.local volumio[1260]: info: [1749572539568] CoreMusicLibrary::Adding element YouTube2 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:22:19 volumio.local.local sudo[1501]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 18:22:19 volumio.local.local sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:19 volumio.local.local sudo[1501]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:19 volumio.local.local volumio[1260]: info: [1749572539608] CoreMusicLibrary::Adding element YouTube Music Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:19 volumio.local.local volumio[1260]: info: [1749572539614] CoreMusicLibrary::Adding element 80s80s Radio Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:19 volumio.local.local volumio[1260]: info: [1749572539632] CoreMusicLibrary::Adding element Radio Paradise Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:22:19 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Volumio Calling Home Jun 10 18:22:19 volumio.local.local sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 10 18:22:19 volumio.local.local sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:19 volumio.local.local systemd[1]: Started RoonBridge. Jun 10 18:22:19 volumio.local.local sudo[1519]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Discovery: adding 99c6faf8-7221-47c3-a127-4993511b63e0 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Discovery: Found device Volumio.local.local Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Discovery: this is already registered, 99c6faf8-7221-47c3-a127-4993511b63e0 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Discovery: Found device Volumio.local.local Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 50 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 51 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 52 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 53 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 54 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 55 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 56 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 57 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 58 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 59 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 60 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 61 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 62 Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 63 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: Volumio called home Jun 10 18:22:19 volumio.local.local volumio[1260]: info: MPD Permissions set Jun 10 18:22:19 volumio.local.local volumio[1260]: info: MPD Permissions set Jun 10 18:22:19 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 64 Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 18:22:19 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:20 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 65 Jun 10 18:22:20 volumio.local.local volumio[1260]: info: Starting Shairport Sync Jun 10 18:22:20 volumio.local.local volumio[1260]: info: Starting Shairport Sync Jun 10 18:22:20 volumio.local.local volumio[1260]: info: Starting Shairport Sync Jun 10 18:22:20 volumio.local.local sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 18:22:20 volumio.local.local sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:20 volumio.local.local systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 18:22:20 volumio.local.local systemd[1]: shairport-sync.service: Succeeded. Jun 10 18:22:20 volumio.local.local systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 18:22:20 volumio.local.local sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 18:22:20 volumio.local.local sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 18:22:20 volumio.local.local systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 18:22:20 volumio.local.local sudo[1555]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:20 volumio.local.local sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:20 volumio.local.local sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:20 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 66 Jun 10 18:22:20 volumio.local.local systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 18:22:20 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 67 Jun 10 18:22:20 volumio.local.local systemd[1]: shairport-sync.service: Succeeded. Jun 10 18:22:20 volumio.local.local systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 18:22:20 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 68 Jun 10 18:22:20 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 69 Jun 10 18:22:20 volumio.local.local systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 18:22:20 volumio.local.local sudo[1563]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:20 volumio.local.local sudo[1560]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:20 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.57 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 70 Jun 10 18:22:20 volumio.local.local volumio[1260]: info: Shairport-Sync Started Jun 10 18:22:20 volumio.local.local volumio[1260]: Error adding Membership: Error: addMembership EINVAL Jun 10 18:22:20 volumio.local.local volumio[1260]: info: Shairport-Sync Started Jun 10 18:22:20 volumio.local.local volumio[1260]: info: Shairport-Sync Started Jun 10 18:22:20 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 71 Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.006 Warn: get lock file path: /tmp/.rnbgem0- Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 10 18:22:20 volumio.local.local volumio[1260]: info: Listing playlists Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetQueue Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CoreStateMachine::getQueue Jun 10 18:22:20 volumio.local.local volumio[1260]: info: CorePlayQueue::getQueue Jun 10 18:22:20 volumio.local.local mpd[1525]: Jun 10 16:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 18:22:20 volumio.local.local systemd[1]: Started Music Player Daemon. Jun 10 18:22:20 volumio.local.local sudo[1460]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:20 volumio.local.local sudo[1479]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.419 Trace: [childprocess] using unix child process Jun 10 18:22:20 volumio.local.local volumio[1260]: error: MPD error: The expression evaluated to a falsy value: Jun 10 18:22:20 volumio.local.local volumio[1260]: assert.ok(self.idling) Jun 10 18:22:20 volumio.local.local volumio[1260]: error: The expression evaluated to a falsy value: Jun 10 18:22:20 volumio.local.local volumio[1260]: assert.ok(self.idling) Jun 10 18:22:20 volumio.local.local volumio[1260]: info: MPD running with PID1525 Jun 10 18:22:20 volumio.local.local volumio[1260]: ,establishing connection Jun 10 18:22:20 volumio.local.local volumio[1260]: error: updateQueue error: null Jun 10 18:22:20 volumio.local.local volumio[1260]: error: updateQueue error: null Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.475 Debug: PathForResource, filename: ../.update Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.506 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.513 Debug: PathForResource, filename: RAATServer Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.513 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.513 Debug: PathForResource, filename: RoonBridgeHelper Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.514 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: Initializing Jun 10 18:22:20 volumio.local.local RoonBridge[1529]: 00:00:00.549 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jun 10 18:22:21 volumio.local.local RoonBridge[1529]: 00:00:00.577 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jun 10 18:22:21 volumio.local.local volumio[1260]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 10 18:22:21 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 18:22:21 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:21 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:21 volumio.local.local volumio[1260]: info: CoreStateMachine::pushState Jun 10 18:22:21 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:21 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushState Jun 10 18:22:21 volumio.local.local volumio[1260]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Jun 10 18:22:21 volumio.local.local volumio[1260]: (TypeError) Cannot read property 'vol' of undefined Jun 10 18:22:21 volumio.local.local volumio[1260]: TypeError: Cannot read property 'vol' of undefined Jun 10 18:22:21 volumio.local.local volumio[1260]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Jun 10 18:22:21 volumio.local.local volumio[1260]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jun 10 18:22:21 volumio.local.local volumio[1260]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Jun 10 18:22:21 volumio.local.local volumio[1260]: at async /data/plugins/music_service/ytcr/dist/index.js:326:13 Jun 10 18:22:21 volumio.local.local RoonBridge[1529]: Not Running (.o) Jun 10 18:22:21 volumio.local.local volumio[1260]: info: Completed starting Core Plugins Jun 10 18:22:21 volumio.local.local volumio[1260]: info: ------------------------------------------- Jun 10 18:22:21 volumio.local.local volumio[1260]: info: ----- MyVolumio plugins startup ---- Jun 10 18:22:21 volumio.local.local volumio[1260]: info: ------------------------------------------- Jun 10 18:22:21 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 10 18:22:21 volumio.local.local RoonBridge[1529]: Running Jun 10 18:22:21 volumio.local.local sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 18:22:21 volumio.local.local sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 18:22:21 volumio.local.local sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 10 18:22:21 volumio.local.local sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:21 volumio.local.local sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:21 volumio.local.local sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:21 volumio.local.local sudo[1626]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:21 volumio.local.local sudo[1627]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:21 volumio.local.local systemd[1]: Started UPnP Renderer front-end to MPD. Jun 10 18:22:21 volumio.local.local sudo[1630]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:21 volumio.local.local RoonBridge[1529]: 00:00:00.007 Warn: get lock file path: /tmp/.rnbhgem0- Jun 10 18:22:21 volumio.local.local volumio[1260]: info: Upmpdcli Daemon Started Jun 10 18:22:21 volumio.local.local volumio[1646]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 10 18:22:22 volumio.local.local systemd[1]: systemd-fsckd.service: Succeeded. Jun 10 18:22:23 volumio.local.local volumio-remote-updater[565]: [2025-06-10 16:22:23] [connect] Successful connection Jun 10 18:22:23 volumio.local.local volumio-remote-updater[565]: [2025-06-10 16:22:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749572543 101 Jun 10 18:22:23 volumio.local.local volumio[1260]: 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: 72 Jun 10 18:22:23 volumio.local.local volumio[1646]: .........................................++++ Jun 10 18:22:24 volumio.local.local volumio[1646]: ..........++++ Jun 10 18:22:24 volumio.local.local volumio[1646]: e is 65537 (0x010001) Jun 10 18:22:24 volumio.local.local volumio[1646]: writing RSA key Jun 10 18:22:24 volumio.local.local systemd[1]: systemd-hostnamed.service: Succeeded. Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 10 18:22:29 volumio.local.local volumio[1260]: info: Adding plugin bluetooth to MyMusic Plugins Jun 10 18:22:29 volumio.local.local volumio[1260]: info: Adding plugin multiroom to MyMusic Plugins Jun 10 18:22:29 volumio.local.local volumio[1260]: info: Adding plugin metavolumio to MyMusic Plugins Jun 10 18:22:29 volumio.local.local volumio[1260]: info: Adding plugin cd_controller to MyMusic Plugins Jun 10 18:22:29 volumio.local.local volumio[1260]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 10 18:22:29 volumio.local.local volumio[1260]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 10 18:22:29 volumio.local.local volumio[1260]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 10 18:22:29 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 10 18:22:30 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 10 18:22:30 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 10 18:22:30 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:30 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:30 volumio.local.local volumio[1260]: info: Starting MyVolumio Remote Streaming Endpoints Jun 10 18:22:30 volumio.local.local volumio[1260]: info: MyVolumio login type: Token Jun 10 18:22:30 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 10 18:22:30 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 10 18:22:30 volumio.local.local wpa_supplicant[971]: wlan0: WPA: Group rekeying completed with cc:ce:1e:80:25:64 [GTK=CCMP] Jun 10 18:22:31 volumio.local.local volumio[1260]: info: Starting Streaming Service Transparent Proxy Jun 10 18:22:31 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 10 18:22:31 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 10 18:22:31 volumio.local.local volumio[1260]: info: Streaming services startup Jun 10 18:22:31 volumio.local.local volumio[1260]: info: Starting Streaming Daemon Jun 10 18:22:31 volumio.local.local sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 18:22:31 volumio.local.local sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:31 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 10 18:22:31 volumio.local.local sudo[1741]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:31 volumio.local.local volumio[1260]: error: Cannot start Volumio Streaming Daemon Jun 10 18:22:31 volumio.local.local volumio[1260]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 10 18:22:31 volumio.local.local volumio[1260]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 10 18:22:32 volumio.local.local volumio[1260]: STREAMING PROXY: Starting server on port 3245 Jun 10 18:22:32 volumio.local.local volumio[1260]: Node JS runtime: 14 Jun 10 18:22:32 volumio.local.local volumio[1260]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 10 18:22:32 volumio.local.local volumio[1260]: info: MyVolumio token set successfully Jun 10 18:22:32 volumio.local.local volumio[1260]: info: MYVOLUMIO: Adding device Jun 10 18:22:32 volumio.local.local volumio[1260]: info: MYVOLUMIO: Evaluating Server Jun 10 18:22:33 volumio.local.local volumio[1260]: info: MyVolumio Plan changed: superstar Jun 10 18:22:33 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Adding audio output: Jun 10 18:22:33 volumio.local.local volumio[1260]: info: MYVOLUMIO: Adding device Jun 10 18:22:33 volumio.local.local volumio[1260]: info: MYVOLUMIO: Evaluating Server Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Remote config written successfully Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Starting Tunnel 1 Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Starting Tunnel Connection Checker Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Completed starting MyVolumio Plugin Jun 10 18:22:33 volumio.local.local volumio[1260]: info: MYVolumio Device enabled Jun 10 18:22:33 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 10 18:22:33 volumio.local.local volumio[1260]: info: MyVolumio status changed Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Streaming services startup Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Starting Streaming Daemon Jun 10 18:22:33 volumio.local.local volumio[1260]: info: Setting Geolocation for MyVolumio to eu7 Jun 10 18:22:33 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:33 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:33 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:33 volumio.local.local sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 18:22:33 volumio.local.local sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:33 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 10 18:22:33 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 10 18:22:33 volumio.local.local sudo[1796]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:33 volumio.local.local volumio[1260]: info: [1749572553603] Starting BluetoothController Jun 10 18:22:33 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 10 18:22:33 volumio.local.local systemd[1]: systemd-timedated.service: Succeeded. Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 10 18:22:34 volumio.local.local sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 10 18:22:34 volumio.local.local sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:34 volumio.local.local sudo[1809]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:34 volumio.local.local volumio[1260]: info: MRS: MultiRoom plugin initialized Jun 10 18:22:34 volumio.local.local volumio[1260]: info: MRS: STOPPING SNAPCLIENT Jun 10 18:22:34 volumio.local.local volumio[1260]: info: MRS: Snap server stop Jun 10 18:22:34 volumio.local.local volumio[1260]: info: MRS: STOPPING volumioStreaming Jun 10 18:22:34 volumio.local.local sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 10 18:22:34 volumio.local.local sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:34 volumio.local.local sudo[1836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 10 18:22:34 volumio.local.local sudo[1836]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:34 volumio.local.local sudo[1842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 10 18:22:34 volumio.local.local sudo[1832]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 10 18:22:34 volumio.local.local sudo[1842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:34 volumio.local.local sudo[1851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 10 18:22:34 volumio.local.local sudo[1851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:34 volumio.local.local sudo[1851]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 10 18:22:34 volumio.local.local sudo[1836]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:34 volumio.local.local sudo[1842]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 10 18:22:34 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Preparing to generate the ALSA configuration file Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Updating MyVolumio device info Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Reading ALSA contributions from plugins. Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Setting Geolocation for MyVolumio to eu7 Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: Removed streaming files Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: volumioStreaming STOPPED Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: SNAPSERVER STOPPED Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: SNAPCLIENT STOPPED Jun 10 18:22:35 volumio.local.local volumio[1260]: error: Cannot start Volumio Streaming Daemon Jun 10 18:22:35 volumio.local.local volumio[1260]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 10 18:22:35 volumio.local.local volumio[1260]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Asound.conf file unchanged, so no further update is needed Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Output device has changed, restarting MPD Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Output device has changed, restarting Shairport Sync Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local sudo[1872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 18:22:35 volumio.local.local sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:35 volumio.local.local sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 18:22:35 volumio.local.local sudo[1872]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:35 volumio.local.local sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local systemd[1]: Stopping Music Player Daemon... Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: Pushing multiroomSync output for this device Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: Pushing multiroomSync output Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding audio output: Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding audio output: Jun 10 18:22:35 volumio.local.local sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio.local.local Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local systemd[1]: mpd.service: Succeeded. Jun 10 18:22:35 volumio.local.local systemd[1]: Stopped Music Player Daemon. Jun 10 18:22:35 volumio.local.local sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:35 volumio.local.local systemd[1]: Starting Music Player Daemon... Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding METAVOLUMIO REST API Endpoints Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Preparing CD Folders Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding CD REST API Endpoints Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Starting UDEV Watcher for CD Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Detecting CD presence with UDEV Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding inputs REST Endpoints Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Scanning Audio Inputs Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Checking against Known Cards name Jun 10 18:22:35 volumio.local.local sudo[1900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Checking against Known Cards name Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Checking against Known Cards name Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding Server instance for streaming Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 10 18:22:35 volumio.local.local volumio[1260]: error: Hi Res Audio Failed Login: Missing Login Data Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 10 18:22:35 volumio.local.local sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Refreshing QOBUZ token Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jun 10 18:22:35 volumio.local.local sudo[1900]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:35 volumio.local.local volumio[1260]: info: QobuzConnect: Starting Qobuz Connect socket and service Jun 10 18:22:35 volumio.local.local volumio[1260]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Stopping AccessToken refresher cron Jun 10 18:22:35 volumio.local.local sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 10 18:22:35 volumio.local.local sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:35 volumio.local.local volumio[1260]: info: AccessToken refresher cron started Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding TIDAL REST API Endpoints Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Successfully Added MyVolumio device Jun 10 18:22:35 volumio.local.local sudo[1918]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Successfully Updated MyVolumio device Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MPD Permissions set Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Successfully Added MyVolumio device Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local sudo[1928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 10 18:22:35 volumio.local.local sudo[1928]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 18:22:35 volumio.local.local sudo[1894]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local systemd[1]: Started Volumio Qobuz Connect Service. Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Starting Shairport Sync Jun 10 18:22:35 volumio.local.local sudo[1928]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:35 volumio.local.local sudo[1947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 18:22:35 volumio.local.local sudo[1947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Access Token successfully retrieved Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 18:22:35 volumio.local.local volumio[1260]: info: [1749572555795] CoreMusicLibrary::Adding element QOBUZ Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:22:35 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:22:35 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:22:35 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:22:35 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:22:35 volumio.local.local volumio[1260]: Cannot find translation for source QOBUZ Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Stopping AccessToken refresher cron for QOBUZ Jun 10 18:22:35 volumio.local.local systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 18:22:35 volumio.local.local systemd[1]: shairport-sync.service: Succeeded. Jun 10 18:22:35 volumio.local.local systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 18:22:35 volumio.local.local volumio[1260]: info: AccessToken refresher cron started for QOBUZ Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding QOBUZ REST API Endpoints Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 10 18:22:35 volumio.local.local systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 18:22:35 volumio.local.local volumio[1260]: [Metrics] CommandRouter: 29s 866.20ms Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumiosetStartupVolume Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:35 volumio.local.local sudo[1947]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:35 volumio.local.local volumio[1260]: info: VolumeController:: Setting startup Volume 20 Jun 10 18:22:35 volumio.local.local volumio[1260]: info: VolumeController::SetAlsaVolume20 Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::Close All Modals sent Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::Close All Modals sent Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreStateMachine::pushState Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushState Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Jun 10 18:22:35 volumio.local.local volumio[1260]: info: MRS: Pushing multiroomSync output Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Bluetooth name changed to Volumio.local.local Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Shairport-Sync Started Jun 10 18:22:35 volumio.local.local volumio[1260]: info: Executing endpoint qc_getconfig Jun 10 18:22:35 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 10 18:22:35 volumio.local.local qobuz-connect[1941]: 20250610 16:22:35.934 [1941.1941] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 10 18:22:35 volumio.local.local volumio[1260]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 10 18:22:35 volumio.local.local volumio[1260]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 10 18:22:35 volumio.local.local qobuz-connect[1941]: 20250610 16:22:35.996 [1941.1941] INFO VolumeManager: [0x813c3220]: Setting new playback volume: 75 Jun 10 18:22:35 volumio.local.local qobuz-connect[1941]: 20250610 16:22:35.996 [1941.1941] INFO VolumeManager: [0x813c3220]: Setting new mute state: 0 Jun 10 18:22:35 volumio.local.local qobuz-connect[1941]: 20250610 16:22:35.996 [1941.1941] INFO QobuzConnect: [0x813c3bf0]: Client initialized! Jun 10 18:22:35 volumio.local.local qobuz-connect[1941]: 20250610 16:22:35.996 [1941.1941] INFO SampleApp: Starting Avahi advertising, name: Volumio.local.local, service name: _qobuz-connect._tcp Jun 10 18:22:36 volumio.local.local qobuz-connect[1941]: 20250610 16:22:36.003 [1941.1941] INFO LocalConfigManager: [0x813c2b00]: Starting Local Configuration server Jun 10 18:22:36 volumio.local.local qobuz-connect[1941]: 20250610 16:22:36.003 [1941.1941] INFO SampleApp: Starting Local configuration server Jun 10 18:22:36 volumio.local.local qobuz-connect[1941]: 20250610 16:22:36.003 [1941.1941] INFO SampleApp: Connected to UNIX socket client 0x813b8ed8 Jun 10 18:22:36 volumio.local.local qobuz-connect[1941]: 20250610 16:22:36.085 [1941.1941] INFO SampleApp: Playback volume changed: 75 Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:36 volumio.local.local volumio[1260]: info: Updating MyVolumio device info Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:22:36 volumio.local.local mpd[1920]: Jun 10 16:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 18:22:36 volumio.local.local systemd[1]: Started Music Player Daemon. Jun 10 18:22:36 volumio.local.local sudo[1875]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 18:22:36 volumio.local.local volumio[1260]: amixer: Mixer hw:0 load error: No such device or address Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 18:22:36 volumio.local.local volumio[1260]: amixer: Mixer hw:0 load error: No such device or address Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 18:22:36 volumio.local.local volumio[1260]: error: updateQueue error: null Jun 10 18:22:36 volumio.local.local volumio[1260]: info: Successfully Updated MyVolumio device Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 18:22:36 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 10 18:22:38 volumio.local.local sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 10 18:22:38 volumio.local.local sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:38 volumio.local.local systemd[1]: Started MyVolumio SSH Tunnel. Jun 10 18:22:38 volumio.local.local sudo[2008]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:38 volumio.local.local autossh[2016]: port set to 0, monitoring disabled Jun 10 18:22:38 volumio.local.local autossh[2016]: starting ssh (count 1) Jun 10 18:22:38 volumio.local.local volumio[1260]: info: Remote SSH Started Jun 10 18:22:38 volumio.local.local autossh[2016]: ssh child pid is 2021 Jun 10 18:22:38 volumio.local.local volumiossh-tunnel[2014]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Jun 10 18:22:38 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 73 Jun 10 18:22:38 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:38 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:38 volumio.local.local sudo[2023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 10 18:22:38 volumio.local.local sudo[2023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:38 volumio.local.local systemd[1]: Started Volumio Bluetooth Module. Jun 10 18:22:38 volumio.local.local sudo[2023]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:38 volumio.local.local volumio[1260]: info: Volumio BT Module successfully started Jun 10 18:22:38 volumio.local.local sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jun 10 18:22:38 volumio.local.local sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:38 volumio.local.local volumiobt[2029]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jun 10 18:22:38 volumio.local.local sudo[2031]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:39 volumio.local.local sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 10 18:22:39 volumio.local.local sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:22:39 volumio.local.local volumiobt[2029]: pulseaudio: no process found Jun 10 18:22:39 volumio.local.local sudo[2043]: pam_unix(sudo:session): session closed for user root Jun 10 18:22:39 volumio.local.local dbus-daemon[571]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=2054 comm="/usr/bin/pulseaudio --high-priority=true ") Jun 10 18:22:39 volumio.local.local systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jun 10 18:22:39 volumio.local.local volumio[1260]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 18:22:39 volumio.local.local kernel: IPVS: Creating netns size=1496 id=3 Jun 10 18:22:39 volumio.local.local kernel: IPVS: ftp: loaded support on port[0] = 21 Jun 10 18:22:39 volumio.local.local dbus-daemon[571]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jun 10 18:22:39 volumio.local.local systemd[1]: Started RealtimeKit Scheduling Policy Service. Jun 10 18:22:39 volumio.local.local rtkit-daemon[2055]: Successfully called chroot. Jun 10 18:22:39 volumio.local.local rtkit-daemon[2055]: Successfully dropped privileges. Jun 10 18:22:39 volumio.local.local rtkit-daemon[2055]: Successfully limited resources. Jun 10 18:22:39 volumio.local.local rtkit-daemon[2055]: Running. Jun 10 18:22:39 volumio.local.local rtkit-daemon[2055]: Watchdog thread running. Jun 10 18:22:39 volumio.local.local dbus-daemon[571]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=2055 comm="/usr/lib/rtkit/rtkit-daemon ") Jun 10 18:22:39 volumio.local.local rtkit-daemon[2055]: Canary thread running. Jun 10 18:22:39 volumio.local.local systemd[1]: Starting Authorization Manager... Jun 10 18:22:39 volumio.local.local volumio[1260]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 18:22:39 volumio.local.local polkitd[2059]: started daemon version 0.105 using authority implementation `local' version `0.105' Jun 10 18:22:39 volumio.local.local dbus-daemon[571]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jun 10 18:22:39 volumio.local.local systemd[1]: Started Authorization Manager. Jun 10 18:22:39 volumio.local.local pulseaudio[2054]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 10 18:22:39 volumio.local.local pulseaudio[2054]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Applying permissions Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Setting BT discoverable and pairable Jun 10 18:22:39 volumio.local.local bluetoothd[618]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Jun 10 18:22:39 volumio.local.local bluetoothd[618]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Jun 10 18:22:39 volumio.local.local pulseaudio[2054]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Agent registered Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [bluetooth]# -e power on Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Invalid command in menu main: -e Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Use "help" for a list of available commands in a menu. Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Use "menu " if you want to enter any submenu. Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Use "back" if you want to return to menu main. Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [bluetooth]# agent on Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Agent is already registered Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [bluetooth]# discoverable on Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [bluetooth]# pairable on Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [bluetooth]# agent NoInputNoOutput Jun 10 18:22:39 volumio.local.local volumiobt[2029]: Agent is already registered Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [bluetooth]# default-agent Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [bluetooth]# quit Jun 10 18:22:39 volumio.local.local volumiobt[2029]: [59B blob data] Jun 10 18:22:40 volumio.local.local volumio[1260]: info: MRS: Getting audio outputs on start Jun 10 18:22:40 volumio.local.local volumio[1260]: info: MRS: Requesting all other devices output Jun 10 18:22:42 volumio.local.local volumio[1260]: info: BOOT COMPLETED Jun 10 18:22:45 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 18:22:45 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 74 Jun 10 18:22:45 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:22:45 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:22:49 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 18:22:49 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 18:22:49 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 18:22:49 volumio.local.local volumio-remote-updater[565]: No test mode Jun 10 18:22:49 volumio.local.local volumio-remote-updater[565]: No alpha test mode Jun 10 18:22:49 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 10 18:22:49 volumio.local.local volumio[1260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 10 18:22:49 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 10 18:22:50 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:50 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 18:22:50 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 10 18:22:50 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 18:22:50 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 10 18:22:50 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 18:22:50 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Preload queue cleared Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::ClearQueue Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::stop Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::clearPlayQueue Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::saveQueue Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushQueue Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::addQueueItems Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::addQueueItems Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Preload queue cleared Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Adding Item to queue: webrp/1 Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Exploding uri webrp/1 in service radio_paradise Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushQueue Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::saveQueue Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::updateTrackBlock Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrackBlock Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPlay Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::play index 0 Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::stop Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::play index undefined Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::startPlaybackTimer Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 10 18:23:09 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 18:23:09 volumio.local.local volumio[1260]: info: sendMpdCommand stop took 3 milliseconds Jun 10 18:23:09 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand clear Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Jun 10 18:23:09 volumio.local.local volumio[1260]: ---------------------------- MPD announces system playlist update Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Ignoring MPD Status Update Jun 10 18:23:09 volumio.local.local volumio[1260]: info: sendMpdCommand clear took 2 milliseconds Jun 10 18:23:09 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 10 18:23:09 volumio.local.local volumio[1260]: error: updateQueue error: null Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Jun 10 18:23:09 volumio.local.local volumio[1260]: ---------------------------- MPD announces state update: options Jun 10 18:23:09 volumio.local.local volumio[1260]: info: ------------------------------ 4ms Jun 10 18:23:09 volumio.local.local volumio[1260]: info: sendMpdCommand consume 1 took 3 milliseconds Jun 10 18:23:09 volumio.local.local volumio[1260]: info: ControllerMpd::getState Jun 10 18:23:09 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Jun 10 18:23:09 volumio.local.local volumio[1260]: info: [1749572589611] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Jun 10 18:23:09 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Jun 10 18:23:09 volumio.local.local volumio[1260]: ---------------------------- MPD announces system playlist update Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Ignoring MPD Status Update Jun 10 18:23:09 volumio.local.local volumio[1260]: info: sendMpdCommand status took 3 milliseconds Jun 10 18:23:09 volumio.local.local volumio[1260]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 2 milliseconds Jun 10 18:23:09 volumio.local.local volumio[1260]: verbose: ControllerMpd::parseState Jun 10 18:23:09 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand play Jun 10 18:23:09 volumio.local.local volumio[1260]: info: ControllerMpd::pushState Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::servicePushState Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreStateMachine::pushState Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioPushState Jun 10 18:23:09 volumio.local.local volumio[1260]: info: MRS: Pushing multiroomSync output update for this device Jun 10 18:23:09 volumio.local.local volumio[1260]: info: MRS: Pushing multiroomSync output Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:09 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:09 volumio.local.local volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 10 18:23:09 volumio.local.local volumio[1260]: info: ------------------------------ 29ms Jun 10 18:23:09 volumio.local.local volumio[1260]: info: ------------------------------ 28ms Jun 10 18:23:09 volumio.local.local volumio[1260]: info: sendMpdCommand play took 22 milliseconds Jun 10 18:23:09 volumio.local.local volumio[1260]: info: [1749572589642] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: [1749572590217] [RadioParadise] received new metadata: {"time":261,"artist":"Led Zeppelin","title":"All My Love","album":"In Through the Out Door","year":"1979","cover":"https://img.radioparadise.com/covers/l/B000002JSP.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000002JSP.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000002JSP.jpg"} Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CoreCommandRouter::servicePushState Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002JSP.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"All My Love","title":"All My Love","artist":"Led Zeppelin","album":"In Through the Out Door","streaming":true,"disableUiControls":true,"duration":266,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: CURRENT POSITION 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CoreStateMachine::syncState stateService play Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Jun 10 18:23:10 volumio.local.local volumio[1260]: info: [1749572590220] [RadioParadise] setting new timer with duration of 266000 seconds. Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Prefetching next song Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Jun 10 18:23:10 volumio.local.local volumio[1260]: ---------------------------- MPD announces state update: player Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ControllerMpd::getState Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Jun 10 18:23:10 volumio.local.local volumio[1260]: ---------------------------- MPD announces state update: player Jun 10 18:23:10 volumio.local.local volumio[1260]: info: sendMpdCommand status took 5 milliseconds Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ControllerMpd::getState Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::parseState Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 18:23:10 volumio.local.local volumio[1260]: info: sendMpdCommand status took 2 milliseconds Jun 10 18:23:10 volumio.local.local volumio[1260]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::parseState Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::parseTrackInfo Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ControllerMpd::pushState Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CoreCommandRouter::servicePushState Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ------------------------------ 14ms Jun 10 18:23:10 volumio.local.local volumio[1260]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::parseTrackInfo Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ControllerMpd::pushState Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CoreCommandRouter::servicePushState Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ------------------------------ 10ms Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Jun 10 18:23:10 volumio.local.local volumio[1260]: ---------------------------- MPD announces system playlist update Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Ignoring MPD Status Update Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Jun 10 18:23:10 volumio.local.local volumio[1260]: ---------------------------- MPD announces state update: player Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ControllerMpd::getState Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ------------------------------ 3ms Jun 10 18:23:10 volumio.local.local volumio[1260]: info: sendMpdCommand status took 2 milliseconds Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::parseState Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 18:23:10 volumio.local.local volumio[1260]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 10 18:23:10 volumio.local.local volumio[1260]: verbose: ControllerMpd::parseTrackInfo Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ControllerMpd::pushState Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CoreCommandRouter::servicePushState Jun 10 18:23:10 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:10 volumio.local.local volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 10 18:23:10 volumio.local.local volumio[1260]: info: ------------------------------ 8ms Jun 10 18:23:27 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:27 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 18:23:27 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 18:23:27 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 18:23:27 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 10 18:23:27 volumio.local.local volumio[1260]: info: Received Get System Version Jun 10 18:23:27 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 18:23:27 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jun 10 18:23:32 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:32 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 18:23:32 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 10 18:23:32 volumio.local.local sudo[2212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 10 18:23:32 volumio.local.local sudo[2212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:32 volumio.local.local sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 10 18:23:32 volumio.local.local sudo[2212]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:33 volumio.local.local sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:33 volumio.local.local sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 10 18:23:33 volumio.local.local sudo[2219]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:33 volumio.local.local sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 10 18:23:33 volumio.local.local sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:33 volumio.local.local sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 18:23:33 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 10 18:23:33 volumio.local.local sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:33 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 10 18:23:33 volumio.local.local sudo[2227]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:33 volumio.local.local sudo[2238]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:33 volumio.local.local sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 18:23:33 volumio.local.local sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:33 volumio.local.local sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:33 volumio.local.local sudo[2248]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:33 volumio.local.local sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 18:23:33 volumio.local.local sudo[2260]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:33 volumio.local.local sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:34 volumio.local.local sudo[2273]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:43 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:43 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 18:23:43 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 18:23:43 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 18:23:43 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 10 18:23:43 volumio.local.local volumio[1260]: info: Received Get System Version Jun 10 18:23:43 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 18:23:43 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jun 10 18:23:52 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Jun 10 18:23:52 volumio.local.local volumio[1260]: info: CoreCommandRouter::Reload Ui Jun 10 18:23:53 volumio.local.local sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 18:23:53 volumio.local.local sudo[2382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 18:23:53 volumio.local.local sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:53 volumio.local.local sudo[2379]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:53 volumio.local.local sudo[2382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:53 volumio.local.local sudo[2382]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:53 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 10 18:23:53 volumio.local.local sudo[2400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 18:23:53 volumio.local.local sudo[2400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:53 volumio.local.local sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 18:23:53 volumio.local.local sudo[2400]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:53 volumio.local.local sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:53 volumio.local.local sudo[2403]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:53 volumio.local.local volumio[1260]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.67 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Version/9.0 Mobile/13G36 Safari/601.1 Engine version: 3 Transport: polling Total Clients: 6 Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 10 18:23:53 volumio.local.local volumio[1260]: xcb_connection_has_error() returned true Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Received Get System Info Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Discovery: Getting this device information Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 18:23:53 volumio.local.local volumio-remote-updater[565]: No test mode Jun 10 18:23:53 volumio.local.local volumio-remote-updater[565]: No alpha test mode Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Retrieving Cloud Streaming UI Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Getting Tidal Cloud Configuration Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Getting Qobuz Cloud Configuration Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Asking plugin for UI Config Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Getting Spotify Cloud Configuration Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Asking plugin for UI Config Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Saving Spotify Acccount Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Got it Jun 10 18:23:53 volumio.local.local volumio[1260]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Got Tidal Cloud Configuration Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 10 18:23:53 volumio.local.local volumio[1260]: info: Got it Jun 10 18:23:53 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 10 18:23:54 volumio.local.local volumio[1260]: xcb_connection_has_error() returned true Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Received Get System Info Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Discovery: Getting this device information Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::volumioGetState Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CorePlayQueue::getTrack 0 Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 18:23:54 volumio.local.local volumio-remote-updater[565]: No test mode Jun 10 18:23:54 volumio.local.local volumio-remote-updater[565]: No alpha test mode Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Retrieving Cloud Streaming UI Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Getting Tidal Cloud Configuration Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Getting Qobuz Cloud Configuration Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Asking plugin for UI Config Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Getting Spotify Cloud Configuration Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Asking plugin for UI Config Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Saving Spotify Acccount Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Got it Jun 10 18:23:54 volumio.local.local volumio[1260]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Got Tidal Cloud Configuration Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Got it Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Loading i18n strings for locale de Jun 10 18:23:54 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 10 18:23:54 volumio.local.local volumio[1260]: info: Setting timezone to Europe/Berlin Jun 10 18:23:54 volumio.local.local sudo[2428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 10 18:23:54 volumio.local.local sudo[2428]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:54 volumio.local.local sudo[2428]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:54 volumio.local.local sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Jun 10 18:23:54 volumio.local.local sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:54 volumio.local.local sudo[2438]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:54 volumio.local.local sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 10 18:23:54 volumio.local.local sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:54 volumio.local.local sudo[2448]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:54 volumio.local.local sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Jun 10 18:23:55 volumio.local.local sudo[2458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:55 volumio.local.local dbus-daemon[571]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.45' (uid=0 pid=2463 comm="/usr/bin/timedatectl set-timezone Europe/Berlin ") Jun 10 18:23:55 volumio.local.local systemd[1]: Starting Time & Date Service... Jun 10 18:23:55 volumio.local.local dbus-daemon[571]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 10 18:23:55 volumio.local.local systemd[1]: Started Time & Date Service. Jun 10 18:23:55 volumio.local.local sudo[2458]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:55 volumio.local.local volumio[1260]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 18:23:55 volumio.local.local volumio[1260]: Updating browse sources language Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source QOBUZ Jun 10 18:23:55 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source QOBUZ Jun 10 18:23:55 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 18:23:55 volumio.local.local volumio[1260]: info: Fetching Streaming Services browse cache Jun 10 18:23:55 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 10 18:23:55 volumio.local.local volumio[1260]: info: Loading i18n strings for locale de Jun 10 18:23:55 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 10 18:23:55 volumio.local.local volumio[1260]: info: Setting timezone to null Jun 10 18:23:55 volumio.local.local sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 10 18:23:55 volumio.local.local sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:55 volumio.local.local sudo[2469]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:55 volumio.local.local sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/null /etc/localtime Jun 10 18:23:55 volumio.local.local sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:55 volumio.local.local sudo[2479]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:55 volumio.local.local sudo[2489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 10 18:23:55 volumio.local.local sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:55 volumio.local.local sudo[2489]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:55 volumio.local.local volumio[1260]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime' Jun 10 18:23:55 volumio.local.local volumio[1260]: error: Could not set timezone: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/localtime Jun 10 18:23:55 volumio.local.local volumio[1260]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime' Jun 10 18:23:55 volumio.local.local sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone null Jun 10 18:23:55 volumio.local.local sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:55 volumio.local.local sudo[2499]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:55 volumio.local.local volumio[1260]: Failed to set time zone: Invalid time zone 'null' Jun 10 18:23:55 volumio.local.local volumio[1260]: info: Could not set timezone, retrying Jun 10 18:23:55 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 18:23:55 volumio.local.local volumio[1260]: Updating browse sources language Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source QOBUZ Jun 10 18:23:55 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:23:55 volumio.local.local volumio[1260]: Cannot find translation for source QOBUZ Jun 10 18:23:55 volumio.local.local volumio[1260]: info: Fetching Streaming Services browse cache Jun 10 18:23:56 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 10 18:23:56 volumio.local.local volumio[1260]: info: Loading i18n strings for locale de Jun 10 18:23:56 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 10 18:23:56 volumio.local.local volumio[1260]: info: Setting timezone to Europe/Berlin Jun 10 18:23:56 volumio.local.local sudo[2509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 10 18:23:56 volumio.local.local sudo[2509]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:56 volumio.local.local sudo[2509]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:56 volumio.local.local sudo[2519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Berlin /etc/localtime Jun 10 18:23:56 volumio.local.local sudo[2519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:56 volumio.local.local sudo[2519]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:56 volumio.local.local sudo[2529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 10 18:23:56 volumio.local.local sudo[2529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:56 volumio.local.local sudo[2529]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:56 volumio.local.local sudo[2539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Berlin Jun 10 18:23:56 volumio.local.local sudo[2539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:56 volumio.local.local sudo[2539]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:56 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 10 18:23:56 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 10 18:23:56 volumio.local.local sudo[2549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 18:23:56 volumio.local.local sudo[2549]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:56 volumio.local.local volumio[1260]: Updating browse sources language Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source QOBUZ Jun 10 18:23:56 volumio.local.local volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source YouTube2 Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source YouTube Music Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source 80s80s Radio Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source Radio Paradise Jun 10 18:23:56 volumio.local.local volumio[1260]: Cannot find translation for source QOBUZ Jun 10 18:23:56 volumio.local.local sudo[2556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone null Jun 10 18:23:56 volumio.local.local sudo[2556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 18:23:56 volumio.local.local sudo[2556]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:56 volumio.local.local volumio[1260]: Failed to set time zone: Invalid time zone 'null' Jun 10 18:23:56 volumio.local.local volumio[1260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 18:23:56 volumio.local.local volumio[1260]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'null' Jun 10 18:23:56 volumio.local.local volumio[1260]: Failed to set time zone: Invalid time zone 'null' Jun 10 18:23:56 volumio.local.local volumio[1260]: at checkExecSyncError (child_process.js:790:11) Jun 10 18:23:56 volumio.local.local volumio[1260]: at execSync (child_process.js:863:15) Jun 10 18:23:56 volumio.local.local volumio[1260]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9) Jun 10 18:23:56 volumio.local.local volumio[1260]: at listOnTimeout (internal/timers.js:557:17) Jun 10 18:23:56 volumio.local.local volumio[1260]: at processTimers (internal/timers.js:500:7) { Jun 10 18:23:56 volumio.local.local volumio[1260]: status: 1, Jun 10 18:23:56 volumio.local.local volumio[1260]: signal: null, Jun 10 18:23:56 volumio.local.local volumio[1260]: output: [ null, '', "Failed to set time zone: Invalid time zone 'null'\n" ], Jun 10 18:23:56 volumio.local.local volumio[1260]: pid: 2555, Jun 10 18:23:56 volumio.local.local volumio[1260]: stdout: '', Jun 10 18:23:56 volumio.local.local volumio[1260]: stderr: "Failed to set time zone: Invalid time zone 'null'\n" Jun 10 18:23:56 volumio.local.local volumio[1260]: } Jun 10 18:23:56 volumio.local.local volumio[1260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 18:23:56 volumio.local.local sudo[2549]: pam_unix(sudo:session): session closed for user root Jun 10 18:23:57 volumio.local.local sudo[2577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 18:22 Jun 10 18:23:57 volumio.local.local sudo[2577]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 03 Jun 2025 10:59:11 AM CEST" VOLUMIO_VERSION="3.813" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="4ab7906c45503ee0ba719461ff7118b6"