Jun 03 11:28:00 hp4 wpa_supplicant[1119]: wlan0: SME: Trying to authenticate with f8:d9:b8:39:0f:70 (SSID='Topteam' freq=5540 MHz) Jun 03 11:28:00 hp4 kernel: wlan0: authenticate with f8:d9:b8:39:0f:70 (local address=34:f6:4b:b0:6c:4c) Jun 03 11:28:00 hp4 kernel: wlan0: send auth to f8:d9:b8:39:0f:70 (try 1/3) Jun 03 11:28:00 hp4 kernel: wlan0: authenticated Jun 03 11:28:00 hp4 wpa_supplicant[1119]: wlan0: Trying to associate with f8:d9:b8:39:0f:70 (SSID='Topteam' freq=5540 MHz) Jun 03 11:28:00 hp4 kernel: wlan0: associate with f8:d9:b8:39:0f:70 (try 1/3) Jun 03 11:28:00 hp4 kernel: wlan0: RX AssocResp from f8:d9:b8:39:0f:70 (capab=0x1111 status=0 aid=4) Jun 03 11:28:00 hp4 wpa_supplicant[1119]: wlan0: Associated with f8:d9:b8:39:0f:70 Jun 03 11:28:00 hp4 kernel: wlan0: associated Jun 03 11:28:00 hp4 wpa_supplicant[1119]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 03 11:28:00 hp4 kernel: wlan0: Limiting TX power to 23 (26 - 3) dBm as advertised by f8:d9:b8:39:0f:70 Jun 03 11:28:00 hp4 wpa_supplicant[1119]: wlan0: WPA: Key negotiation completed with f8:d9:b8:39:0f:70 [PTK=CCMP GTK=CCMP] Jun 03 11:28:00 hp4 wpa_supplicant[1119]: wlan0: CTRL-EVENT-CONNECTED - Connection to f8:d9:b8:39:0f:70 completed [id=0 id_str=] Jun 03 11:28:00 hp4 dhcpcd[783]: wlan0: carrier acquired Jun 03 11:28:00 hp4 dhcpcd[783]: wlan0: connected to Access Point: Topteam Jun 03 11:28:00 hp4 dhcpcd[783]: wlan0: IAID 4b:b0:6c:4c Jun 03 11:28:00 hp4 dhcpcd[783]: wlan0: probing address 192.168.203.40/24 Jun 03 11:28:00 hp4 sudo[1151]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 03 11:28:00 hp4 sudo[1151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:00 hp4 sudo[1151]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:00 hp4 dhcpcd[783]: wlan0: soliciting an IPv6 router Jun 03 11:28:01 hp4 sudo[1155]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 03 11:28:01 hp4 sudo[1155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:01 hp4 sudo[1155]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:02 hp4 sudo[1159]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 03 11:28:02 hp4 sudo[1159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:02 hp4 sudo[1159]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:02 hp4 volumio-remote-updater[697]: [2025-06-03 11:28:02] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 03 11:28:02 hp4 volumio-remote-updater[697]: [2025-06-03 11:28:02] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 03 11:28:02 hp4 volumio-remote-updater[697]: [2025-06-03 11:28:02] [error] handle_connect error: Connection refused Jun 03 11:28:03 hp4 sudo[1163]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 03 11:28:03 hp4 sudo[1163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:03 hp4 sudo[1163]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:04 hp4 sudo[1167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 03 11:28:04 hp4 sudo[1167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:04 hp4 sudo[1167]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:05 hp4 avahi-daemon[678]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.203.40. Jun 03 11:28:05 hp4 dhcpcd[783]: wlan0: using static address 192.168.203.40/24 Jun 03 11:28:05 hp4 avahi-daemon[678]: New relevant interface wlan0.IPv4 for mDNS. Jun 03 11:28:05 hp4 avahi-daemon[678]: Registering new address record for 192.168.203.40 on wlan0.IPv4. Jun 03 11:28:05 hp4 dhcpcd[783]: wlan0: adding route to 192.168.203.0/24 Jun 03 11:28:05 hp4 dhcpcd[783]: wlan0: adding default route via 192.168.203.1 Jun 03 11:28:05 hp4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 03 11:28:05 hp4 systemd[1]: welcome.service: Deactivated successfully. Jun 03 11:28:05 hp4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 03 11:28:05 hp4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 03 11:28:05 hp4 systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 03 11:28:05 hp4 welcome[1184]: Resolved ip:[1] 192.168.203.40 Jun 03 11:28:05 hp4 sudo[1191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 03 11:28:05 hp4 sudo[1191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:05 hp4 sudo[1191]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:05 hp4 setdatetime-helper.sh[1177]: Time is already synchronized. Jun 03 11:28:05 hp4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 03 11:28:05 hp4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 03 11:28:05 hp4 systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 03 11:28:05 hp4 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 03 11:28:05 hp4 sudo[1201]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 11:28:05 hp4 sudo[1201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 03 11:28:05 hp4 sudo[1201]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:05 hp4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 03 11:28:05 hp4 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 03 11:28:05 hp4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:05 hp4 systemd[1]: shairport-sync.service: Consumed 1.733s CPU time. Jun 03 11:28:05 hp4 avahi-daemon[678]: Got SIGTERM, quitting. Jun 03 11:28:05 hp4 avahi-daemon[678]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.203.40. Jun 03 11:28:05 hp4 avahi-daemon[678]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 03 11:28:05 hp4 systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 03 11:28:05 hp4 avahi-daemon[678]: avahi-daemon 0.8 exiting. Jun 03 11:28:05 hp4 systemd[1]: avahi-daemon.service: Deactivated successfully. Jun 03 11:28:05 hp4 systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 03 11:28:05 hp4 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 03 11:28:05 hp4 avahi-daemon[1206]: Process 678 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jun 03 11:28:05 hp4 avahi-daemon[1206]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 03 11:28:05 hp4 avahi-daemon[1206]: Successfully dropped root privileges. Jun 03 11:28:05 hp4 avahi-daemon[1206]: avahi-daemon 0.8 starting up. Jun 03 11:28:05 hp4 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 03 11:28:05 hp4 avahi-daemon[1206]: Successfully called chroot(). Jun 03 11:28:05 hp4 avahi-daemon[1206]: Successfully dropped remaining capabilities. Jun 03 11:28:05 hp4 avahi-daemon[1206]: Loading service file /services/volumio.service. Jun 03 11:28:05 hp4 avahi-daemon[1206]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.203.40. Jun 03 11:28:05 hp4 avahi-daemon[1206]: New relevant interface wlan0.IPv4 for mDNS. Jun 03 11:28:05 hp4 avahi-daemon[1206]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 03 11:28:05 hp4 avahi-daemon[1206]: New relevant interface lo.IPv4 for mDNS. Jun 03 11:28:05 hp4 avahi-daemon[1206]: Network interface enumeration completed. Jun 03 11:28:05 hp4 avahi-daemon[1206]: Registering new address record for 192.168.203.40 on wlan0.IPv4. Jun 03 11:28:05 hp4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:05 hp4 avahi-daemon[1206]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 03 11:28:05 hp4 systemd[1]: Started nmbd.service - Samba NMB Daemon. Jun 03 11:28:05 hp4 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jun 03 11:28:05 hp4 systemd[1]: Started wireless.service - Wireless Services. Jun 03 11:28:05 hp4 systemd[1]: Started volumio.service - Volumio Backend Module. Jun 03 11:28:05 hp4 systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Jun 03 11:28:05 hp4 systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Jun 03 11:28:05 hp4 systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Jun 03 11:28:05 hp4 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jun 03 11:28:05 hp4 volumio-cpu-tweak[1236]: Setting RT Priority for mpd Jun 03 11:28:05 hp4 volumio-cpu-tweak[1267]: pid 35's current scheduling policy: SCHED_OTHER Jun 03 11:28:05 hp4 volumio-cpu-tweak[1267]: pid 35's current scheduling priority: 0 Jun 03 11:28:05 hp4 volumio-cpu-tweak[1236]: Not enough cores to set MPD affinity Jun 03 11:28:05 hp4 volumio-cpu-tweak[1236]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 03 11:28:05 hp4 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jun 03 11:28:06 hp4 startx[1295]: X.Org X Server 1.21.1.7 Jun 03 11:28:06 hp4 startx[1295]: X Protocol Version 11, Revision 0 Jun 03 11:28:06 hp4 startx[1295]: Current Operating System: Linux hp4 6.12.30-volumio #2 SMP PREEMPT_DYNAMIC Wed May 28 19:37:30 BST 2025 x86_64 Jun 03 11:28:06 hp4 startx[1295]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=306bb5a4-bcd2-41f4-9736-57470fd3db84 bootpart=UUID=9D06-866B datapart=UUID=d28988e8-bee4-44c0-a488-7771195b8894 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Jun 03 11:28:06 hp4 startx[1295]: xorg-server 2:21.1.7-3+deb12u9 (https://www.debian.org/support) Jun 03 11:28:06 hp4 startx[1295]: Current version of pixman: 0.42.2 Jun 03 11:28:06 hp4 startx[1295]: Before reporting problems, check http://wiki.x.org Jun 03 11:28:06 hp4 startx[1295]: to make sure that you have the latest version. Jun 03 11:28:06 hp4 startx[1295]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 03 11:28:06 hp4 startx[1295]: (++) from command line, (!!) notice, (II) informational, Jun 03 11:28:06 hp4 startx[1295]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 03 11:28:06 hp4 startx[1295]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jun 3 11:28:06 2025 Jun 03 11:28:06 hp4 startx[1295]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 03 11:28:06 hp4 winbindd[1256]: [2025/06/03 11:28:06.170527, 0] ../../source3/winbindd/winbindd.c:1440(main) Jun 03 11:28:06 hp4 winbindd[1256]: winbindd version 4.17.12-Debian started. Jun 03 11:28:06 hp4 winbindd[1256]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 03 11:28:06 hp4 winbindd[1256]: [2025/06/03 11:28:06.188457, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Jun 03 11:28:06 hp4 winbindd[1256]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 03 11:28:06 hp4 systemd[1]: Started winbind.service - Samba Winbind Daemon. Jun 03 11:28:06 hp4 systemd[1]: Starting smbd.service - Samba SMB Daemon... Jun 03 11:28:06 hp4 soundcard-init.sh[1323]: Simple mixer control 'Master',0 Jun 03 11:28:06 hp4 soundcard-init.sh[1323]: Capabilities: pvolume pvolume-joined pswitch pswitch-joined Jun 03 11:28:06 hp4 soundcard-init.sh[1323]: Playback channels: Mono Jun 03 11:28:06 hp4 soundcard-init.sh[1323]: Limits: Playback 0 - 87 Jun 03 11:28:06 hp4 soundcard-init.sh[1323]: Mono: Playback 65 [75%] [-16.50dB] [on] Jun 03 11:28:06 hp4 systemd[1]: soundcard-init.service: Deactivated successfully. Jun 03 11:28:06 hp4 avahi-daemon[1206]: Server startup complete. Host name is hp4.local. Local service cookie is 570970940. Jun 03 11:28:06 hp4 smbd[1325]: [2025/06/03 11:28:06.687875, 0] ../../source3/smbd/server.c:1741(main) Jun 03 11:28:06 hp4 smbd[1325]: smbd version 4.17.12-Debian started. Jun 03 11:28:06 hp4 smbd[1325]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 03 11:28:07 hp4 ntpd[1008]: IO: Listen normally on 3 wlan0 192.168.203.40:123 Jun 03 11:28:07 hp4 ntpd[1008]: IO: new interface(s) found: waking up resolver Jun 03 11:28:07 hp4 ntpd[1008]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 03 11:28:07 hp4 avahi-daemon[1206]: Service "hp4" (/services/volumio.service) successfully established. Jun 03 11:28:08 hp4 systemd[1]: Started smbd.service - Samba SMB Daemon. Jun 03 11:28:08 hp4 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 03 11:28:08 hp4 systemd[1]: Reached target graphical.target - Graphical Interface. Jun 03 11:28:08 hp4 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jun 03 11:28:08 hp4 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jun 03 11:28:08 hp4 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jun 03 11:28:08 hp4 systemd[1]: Startup finished in 3.885s (firmware) + 2.614s (loader) + 10.364s (kernel) + 22.974s (userspace) = 39.839s. Jun 03 11:28:09 hp4 volumio[1214]: info: ------------------------------------------- Jun 03 11:28:09 hp4 volumio[1214]: info: ----- Volumio3 ---- Jun 03 11:28:09 hp4 volumio[1214]: info: ------------------------------------------- Jun 03 11:28:09 hp4 volumio[1214]: info: ----- System startup ---- Jun 03 11:28:09 hp4 volumio[1214]: info: ------------------------------------------- Jun 03 11:28:10 hp4 volumio[1214]: info: MYVOLUMIO Environment detected Jun 03 11:28:10 hp4 volumio[1214]: info: Plugin folders cleanup Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning into folder /volumio/app/plugins/ Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning category audio_interface Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning category miscellanea Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning category music_service Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning category plugins.json Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning category system_controller Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning category user_interface Jun 03 11:28:10 hp4 volumio[1214]: info: Scanning into folder /data/plugins/ Jun 03 11:28:10 hp4 volumio[1214]: info: Plugin folders cleanup completed Jun 03 11:28:10 hp4 volumio[1214]: info: ------------------------------------------- Jun 03 11:28:10 hp4 volumio[1214]: info: ----- Core plugins startup ---- Jun 03 11:28:10 hp4 volumio[1214]: info: ------------------------------------------- Jun 03 11:28:10 hp4 volumio[1214]: info: Loading plugins from folder /volumio/app/plugins/ Jun 03 11:28:10 hp4 volumio[1214]: info: Adding plugin upnp to MyMusic Plugins Jun 03 11:28:10 hp4 volumio[1214]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 03 11:28:10 hp4 volumio[1214]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 03 11:28:10 hp4 volumio[1214]: info: Loading plugins from folder /data/plugins/ Jun 03 11:28:10 hp4 volumio[1214]: info: Loading plugin "system"... Jun 03 11:28:10 hp4 volumio[1214]: info: Loading plugin "appearance"... Jun 03 11:28:12 hp4 ntpd[1008]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 03 11:28:12 hp4 ntpd[1008]: DNS: Pool taking: 95.216.138.141 Jun 03 11:28:12 hp4 ntpd[1008]: DNS: Pool taking: 95.216.147.242 Jun 03 11:28:12 hp4 ntpd[1008]: DNS: Pool taking: 37.27.202.175 Jun 03 11:28:12 hp4 ntpd[1008]: DNS: Pool taking: 65.21.242.181 Jun 03 11:28:12 hp4 ntpd[1008]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 03 11:28:12 hp4 volumio-remote-updater[697]: [2025-06-03 11:28:12] [connect] Successful connection Jun 03 11:28:13 hp4 ntpd[1008]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "network"... Jun 03 11:28:13 hp4 volumio[1214]: info: Refreshing Cached IP Addresses Jun 03 11:28:13 hp4 sudo[1462]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 11:28:13 hp4 sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:13 hp4 sudo[1462]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "services"... Jun 03 11:28:13 hp4 sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 11:28:13 hp4 sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:13 hp4 sudo[1464]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "alsa_controller"... Jun 03 11:28:13 hp4 sudo[1474]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 03 11:28:13 hp4 sudo[1474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:13 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "wizard"... Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "networkfs"... Jun 03 11:28:13 hp4 volumio[1214]: info: Starting Udev Watcher for removable devices Jun 03 11:28:13 hp4 volumio[1214]: info: Ignoring mount for partition: boot Jun 03 11:28:13 hp4 volumio[1214]: info: Ignoring mount for partition: volumio Jun 03 11:28:13 hp4 volumio[1214]: info: Ignoring mount for partition: volumio_data Jun 03 11:28:13 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "volumio_command_line_client"... Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "upnp"... Jun 03 11:28:13 hp4 volumio[1214]: info: [1748939293812] Starting Upmpd Daemon Jun 03 11:28:13 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "my_music"... Jun 03 11:28:13 hp4 volumio[1214]: info: Loading plugin "mpd"... Jun 03 11:28:14 hp4 volumio[1214]: info: Loading plugin "upnp_browser"... Jun 03 11:28:15 hp4 sudo[1474]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:15 hp4 volumio[1214]: info: Loading plugin "alarm-clock"... Jun 03 11:28:15 hp4 volumio[1214]: info: Loading plugin "airplay_emulation"... Jun 03 11:28:16 hp4 volumio[1214]: info: Starting Shairport Sync Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "last_100"... Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "webradio"... Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "i2s_dacs"... Jun 03 11:28:16 hp4 volumio[1214]: info: I2S DAC not set, start Auto-detection Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "volumiodiscovery"... Jun 03 11:28:16 hp4 volumio[1214]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 03 11:28:16 hp4 volumio[1214]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:28:16 hp4 volumio[1214]: *** WARNING *** For more information see Jun 03 11:28:16 hp4 volumio[1214]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 03 11:28:16 hp4 volumio[1214]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:28:16 hp4 volumio[1214]: *** WARNING *** For more information see Jun 03 11:28:16 hp4 node[1214]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 03 11:28:16 hp4 node[1214]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:28:16 hp4 node[1214]: *** WARNING *** For more information see Jun 03 11:28:16 hp4 node[1214]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 03 11:28:16 hp4 node[1214]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 03 11:28:16 hp4 node[1214]: *** WARNING *** For more information see Jun 03 11:28:16 hp4 volumio[1214]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 03 11:28:16 hp4 volumio[1214]: info: Discovery: Started advertising with name: hp4 Jun 03 11:28:16 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "outputs"... Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "albumart"... Jun 03 11:28:16 hp4 volumio[1214]: info: Plugin example_plugin is not enabled Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "inputs"... Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "updater_comm"... Jun 03 11:28:16 hp4 volumio[1599]: Forking 1 albumart workers Jun 03 11:28:16 hp4 volumio[1214]: info: Plugin mpdemulation is not enabled Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "rest_api"... Jun 03 11:28:16 hp4 volumio[1214]: info: Loading plugin "websocket"... Jun 03 11:28:16 hp4 volumio[1214]: info: Starting Socket.io Server version 1.7.4 Jun 03 11:28:16 hp4 volumio[1214]: info: Loading i18n strings for locale en Jun 03 11:28:16 hp4 volumio[1214]: Updating browse sources language Jun 03 11:28:16 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::initPlayerControls Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: Express server listening on port 3000 Jun 03 11:28:17 hp4 volumio[1214]: [Metrics] WebUI: 8s 741.33ms Jun 03 11:28:17 hp4 volumio[1214]: info: Setting Device type: x86 Jun 03 11:28:17 hp4 volumio[1214]: info: CoreStateMachine::resetVolumioState Jun 03 11:28:17 hp4 volumio[1214]: info: CoreStateMachine::getcurrentVolume Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 03 11:28:17 hp4 volumio[1214]: info: Completed loading Core Plugins Jun 03 11:28:17 hp4 volumio[1214]: info: Preparing to generate the ALSA configuration file Jun 03 11:28:17 hp4 volumio[1214]: info: Cannot read play queue from file Jun 03 11:28:17 hp4 volumio[1214]: info: Volumio Network Manager: Network status updated: 2 Jun 03 11:28:17 hp4 volumio[1214]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 03 11:28:17 hp4 volumio[1615]: Starting albumart workers Jun 03 11:28:17 hp4 volumio[1214]: verbose: New Socket.io Connection to 192.168.203.40 from 192.168.203.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Jun 03 11:28:17 hp4 volumio[1214]: info: VolumeController:: Volume=49 Mute =false Jun 03 11:28:17 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:28:17 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushState Jun 03 11:28:17 hp4 volumio[1214]: info: CoreStateMachine::updateTrackBlock Jun 03 11:28:17 hp4 volumio[1214]: info: CorePlayQueue::getTrackBlock Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::volumioRetrievevolume Jun 03 11:28:17 hp4 volumio[1214]: info: Asound.conf file unchanged, so no further update is needed Jun 03 11:28:17 hp4 volumio[1214]: info: Output device has changed, restarting MPD Jun 03 11:28:17 hp4 volumio[1214]: info: Output device has changed, restarting Shairport Sync Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:17 hp4 sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:28:17 hp4 sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:17 hp4 sudo[1639]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:17 hp4 sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:28:17 hp4 sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:17 hp4 volumio[1214]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:28:17 hp4 volumio[1214]: info: ___________ START PLUGINS ___________ Jun 03 11:28:17 hp4 volumio[1214]: info: ControllerMpd::onStart: Initializing MPD Jun 03 11:28:17 hp4 volumio[1214]: info: Creating MPD Configuration file Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:28:17 hp4 volumio[1214]: info: [1748939297455] CoreMusicLibrary::Adding element Media Servers Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:17 hp4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 03 11:28:17 hp4 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 03 11:28:17 hp4 sudo[1652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 11:28:17 hp4 sudo[1652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:17 hp4 sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 11:28:17 hp4 sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:17 hp4 systemd[1]: mpd.service: Deactivated successfully. Jun 03 11:28:17 hp4 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 03 11:28:17 hp4 systemd[1]: mpd.socket: Deactivated successfully. Jun 03 11:28:17 hp4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 03 11:28:17 hp4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 03 11:28:17 hp4 sudo[1649]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:17 hp4 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 03 11:28:17 hp4 systemd[1]: systemd-fsckd.service: Deactivated successfully. Jun 03 11:28:17 hp4 volumio[1214]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:28:17 hp4 volumio[1214]: info: [1748939297625] CoreMusicLibrary::Adding element Last_100 Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 03 11:28:17 hp4 volumio[1214]: info: [1748939297628] CoreMusicLibrary::Adding element Webradio Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:17 hp4 sudo[1658]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 11:28:17 hp4 sudo[1658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 03 11:28:17 hp4 sudo[1666]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 03 11:28:17 hp4 sudo[1658]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:17 hp4 volumio[1214]: info: Initializing BBC Radios Jun 03 11:28:17 hp4 volumio[1214]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:17 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:17 hp4 volumio[1214]: info: Volumio Calling Home Jun 03 11:28:18 hp4 volumio[1214]: info: CoreStateMachine::setRepeat null single undefined Jun 03 11:28:18 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushState Jun 03 11:28:18 hp4 volumio[1214]: info: CoreStateMachine::setRandom null Jun 03 11:28:18 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushState Jun 03 11:28:18 hp4 volumio[1214]: verbose: New Socket.io Connection to 192.168.203.40 from 192.168.203.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 03 11:28:18 hp4 volumio[1214]: info: MPD Permissions set Jun 03 11:28:18 hp4 volumio[1214]: info: MPD Permissions set Jun 03 11:28:18 hp4 volumio[1214]: verbose: New Socket.io Connection to 192.168.203.40 from 192.168.203.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool taking: 37.27.51.34 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool taking: 37.27.186.231 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool taking: 95.216.78.223 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool skipping: 95.216.147.242 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool taking: 2606:4700:f1::1 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool taking: 2a01:4f9:3081:399c::4 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool taking: 2a01:4f9:c011:a343:123:123:123:123 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: Pool taking: 2a01:4f9:c012:963::1 Jun 03 11:28:18 hp4 ntpd[1008]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 03 11:28:18 hp4 volumio[1214]: info: VolumeController:: Volume=49 Mute =false Jun 03 11:28:18 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushState Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:18 hp4 volumio[1214]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 03 11:28:18 hp4 volumio[1214]: verbose: New Socket.io Connection to 192.168.203.40 from 192.168.203.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 03 11:28:18 hp4 volumio[1214]: info: Starting Shairport Sync Jun 03 11:28:18 hp4 volumio[1214]: info: Starting Shairport Sync Jun 03 11:28:18 hp4 sudo[1713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 11:28:18 hp4 sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:18 hp4 volumio[1214]: info: Starting Shairport Sync Jun 03 11:28:18 hp4 sudo[1716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 11:28:18 hp4 sudo[1716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:18 hp4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 03 11:28:18 hp4 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 03 11:28:18 hp4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 11:28:18 hp4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:18 hp4 sudo[1713]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:18 hp4 sudo[1718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 03 11:28:18 hp4 sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:18 hp4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 03 11:28:18 hp4 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 03 11:28:18 hp4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:18 hp4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 sudo[1716]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 11:28:18 hp4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 03 11:28:18 hp4 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 11:28:18 hp4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:18 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:18 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: Listing playlists Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 11:28:18 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:18 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 11:28:18 hp4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 03 11:28:18 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:18 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 sudo[1718]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 11:28:18 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:18 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:18 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:18 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:18 hp4 volumio[1214]: info: Listing playlists Jun 03 11:28:18 hp4 volumio[1214]: info: Shairport-Sync Started Jun 03 11:28:18 hp4 volumio[1214]: Error adding Membership: Error: addMembership EINVAL Jun 03 11:28:18 hp4 volumio[1214]: info: Shairport-Sync Started Jun 03 11:28:18 hp4 volumio[1214]: info: Shairport-Sync Started Jun 03 11:28:19 hp4 ntpd[1008]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 03 11:28:19 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 11:28:19 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 11:28:19 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 03 11:28:19 hp4 volumio[1214]: info: Received Get System Version Jun 03 11:28:19 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:19 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 03 11:28:20 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 03 11:28:20 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 03 11:28:20 hp4 mpd[1667]: 2025-06-03T11:28:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 11:28:20 hp4 systemd[1]: Started mpd.service - Music Player Daemon. Jun 03 11:28:20 hp4 sudo[1652]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:20 hp4 sudo[1641]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:20 hp4 volumio[1214]: info: Completed starting Core Plugins Jun 03 11:28:20 hp4 volumio[1214]: info: ------------------------------------------- Jun 03 11:28:20 hp4 volumio[1214]: info: ----- MyVolumio plugins startup ---- Jun 03 11:28:20 hp4 volumio[1214]: info: ------------------------------------------- Jun 03 11:28:20 hp4 volumio[1214]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 03 11:28:20 hp4 volumio[1214]: error: MPD error: The expression evaluated to a falsy value: Jun 03 11:28:20 hp4 volumio[1214]: assert.ok(self.idling) Jun 03 11:28:20 hp4 volumio[1214]: error: The expression evaluated to a falsy value: Jun 03 11:28:20 hp4 volumio[1214]: assert.ok(self.idling) Jun 03 11:28:20 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:28:20 hp4 volumio[1214]: info: MPD running with PID1667 Jun 03 11:28:20 hp4 volumio[1214]: ,establishing connection Jun 03 11:28:20 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:28:20 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 03 11:28:21 hp4 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jun 03 11:28:22 hp4 volumio[1214]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 03 11:28:23 hp4 volumio[1214]: info: Volumio called home Jun 03 11:28:23 hp4 sudo[1791]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 11:28:23 hp4 sudo[1791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:23 hp4 sudo[1791]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:23 hp4 sudo[1793]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 11:28:23 hp4 sudo[1793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:23 hp4 sudo[1793]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:23 hp4 sudo[1797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 03 11:28:23 hp4 sudo[1797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:23 hp4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 03 11:28:23 hp4 sudo[1797]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:23 hp4 volumio[1214]: info: Upmpdcli Daemon Started Jun 03 11:28:24 hp4 volumio[1214]: info: CoreCommandRouter::Close All Modals sent Jun 03 11:28:24 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Jun 03 11:28:24 hp4 ntpd[1008]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 03 11:28:24 hp4 ntpd[1008]: DNS: Pool taking: 195.148.70.12 Jun 03 11:28:24 hp4 ntpd[1008]: DNS: Pool skipping: 37.27.51.34 Jun 03 11:28:24 hp4 ntpd[1008]: DNS: Pool skipping: 37.27.202.175 Jun 03 11:28:24 hp4 ntpd[1008]: DNS: Pool skipping: 95.216.147.242 Jun 03 11:28:24 hp4 ntpd[1008]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 03 11:28:24 hp4 volumio[1214]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 03 11:28:24 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:25 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 11:28:25 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:25 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:25 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:25 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:25 hp4 volumio[1214]: info: Listing playlists Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:25 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 11:28:25 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:25 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:25 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:25 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:25 hp4 volumio[1214]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 03 11:28:26 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 03 11:28:27 hp4 volumio-remote-updater[697]: [2025-06-03 11:28:27] [connect] Successful connection Jun 03 11:28:27 hp4 volumio-remote-updater[697]: [2025-06-03 11:28:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1748939307 101 Jun 03 11:28:27 hp4 volumio[1214]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 8 Jun 03 11:28:28 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:28:28 hp4 volumio[1214]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 03 11:28:28 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 03 11:28:28 hp4 volumio[1214]: info: Received Get System Version Jun 03 11:28:28 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:28 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:28 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:28 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:28 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:28 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:28 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:28 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:29 hp4 upmpdcli[1837]: writing RSA key Jun 03 11:28:33 hp4 volumio[1214]: info: Discovery: adding 11dc1837-0adc-4123-a7a3-1eb91157b062 Jun 03 11:28:33 hp4 volumio[1214]: info: Discovery: Found device hp4 Jun 03 11:28:33 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:33 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:34 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 03 11:28:34 hp4 volumio[1214]: info: CALLMETHOD: system_controller system savePrivacySettings [object Object] Jun 03 11:28:34 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , savePrivacySettings Jun 03 11:28:34 hp4 volumio[1214]: info: CoreCommandRouter::Reload Ui Jun 03 11:28:35 hp4 sudo[1868]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 11:28:35 hp4 sudo[1868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:35 hp4 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 03 11:28:35 hp4 sudo[1870]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 11:28:35 hp4 sudo[1870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:35 hp4 sudo[1868]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:35 hp4 sudo[1870]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:35 hp4 volumio[1214]: verbose: New Socket.io Connection to 192.168.203.40 from 192.168.203.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:35 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 11:28:35 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:35 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:35 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:35 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:35 hp4 volumio[1214]: info: Listing playlists Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 11:28:35 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 03 11:28:35 hp4 volumio[1214]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 03 11:28:36 hp4 volumio[1214]: info: Received Get System Version Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetVisibleSources Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:36 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 03 11:28:36 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:36 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:36 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:36 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:36 hp4 volumio[1214]: info: Listing playlists Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 03 11:28:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:37 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 11:28:37 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:37 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:37 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 11:28:37 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:37 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:37 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:37 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 11:28:38 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:38 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:38 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 03 11:28:38 hp4 volumio[1214]: info: Discovery: this is already registered, 11dc1837-0adc-4123-a7a3-1eb91157b062 Jun 03 11:28:38 hp4 volumio[1214]: info: Discovery: Found device hp4 Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:38 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:38 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:38 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 03 11:28:38 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 03 11:28:40 hp4 volumio[1214]: error: MyVolumio Plugin failed to start in a timely fashion Jun 03 11:28:40 hp4 volumio[1214]: [Metrics] CommandRouter: 31s 668.88ms Jun 03 11:28:40 hp4 volumio[1214]: info: CoreCommandRouter::volumiosetStartupVolume Jun 03 11:28:40 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:28:40 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:40 hp4 volumio[1214]: info: CoreCommandRouter::Close All Modals sent Jun 03 11:28:40 hp4 volumio[1214]: info: CoreCommandRouter::Close All Modals sent Jun 03 11:28:41 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 03 11:28:41 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 03 11:28:41 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 03 11:28:42 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetQueue Jun 03 11:28:42 hp4 volumio[1214]: info: CoreStateMachine::getQueue Jun 03 11:28:42 hp4 volumio[1214]: info: CorePlayQueue::getQueue Jun 03 11:28:45 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 03 11:28:45 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 03 11:28:47 hp4 volumio[1214]: info: BOOT COMPLETED Jun 03 11:28:47 hp4 volumio[1214]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 03 11:28:47 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:47 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:47 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:47 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 03 11:28:48 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 03 11:28:48 hp4 volumio[1214]: info: TuneIn handleBrowseUri: tunein/local Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 03 11:28:50 hp4 volumio[1214]: info: Adding plugin bluetooth to MyMusic Plugins Jun 03 11:28:50 hp4 volumio[1214]: info: Adding plugin multiroom to MyMusic Plugins Jun 03 11:28:50 hp4 volumio[1214]: info: Adding plugin metavolumio to MyMusic Plugins Jun 03 11:28:50 hp4 volumio[1214]: info: Adding plugin cd_controller to MyMusic Plugins Jun 03 11:28:50 hp4 volumio[1214]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 03 11:28:50 hp4 volumio[1214]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 03 11:28:50 hp4 volumio[1214]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 03 11:28:51 hp4 volumio[1214]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 03 11:28:51 hp4 volumio[1214]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 03 11:28:51 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:51 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:51 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:51 hp4 volumio[1214]: info: Starting MyVolumio Remote Streaming Endpoints Jun 03 11:28:51 hp4 volumio[1214]: info: MyVolumio not started Jun 03 11:28:51 hp4 volumio[1214]: info: Initializing device activation check Jun 03 11:28:51 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 11:28:51 hp4 volumio[1214]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 03 11:28:51 hp4 volumio[1214]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 03 11:28:52 hp4 volumio[1214]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 03 11:28:52 hp4 volumio[1214]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 03 11:28:52 hp4 volumio[1214]: info: Streaming services startup Jun 03 11:28:52 hp4 volumio[1214]: info: Starting Streaming Daemon Jun 03 11:28:52 hp4 sudo[1935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 03 11:28:52 hp4 sudo[1935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 03 11:28:52 hp4 volumio[1214]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 03 11:28:52 hp4 sudo[1935]: pam_unix(sudo:session): session closed for user root Jun 03 11:28:52 hp4 volumio[1214]: error: Cannot start Volumio Streaming Daemon Jun 03 11:28:52 hp4 volumio[1214]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 03 11:28:52 hp4 volumio[1214]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:28:53 hp4 volumio[1214]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 03 11:28:53 hp4 volumio[1214]: info: Received Get System Version Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:53 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:53 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:53 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:28:53 hp4 volumio[1214]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 03 11:28:53 hp4 volumio[1214]: info: Received Get System Version Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:28:53 hp4 volumio[1214]: info: Received Get System Info Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 11:28:53 hp4 volumio[1214]: info: Discovery: Getting this device information Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:28:53 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:28:53 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 11:28:55 hp4 volumio[1214]: error: Cannot read properties of undefined (reading 'indexOf') Jun 03 11:28:55 hp4 volumio[1214]: error: Failed to execute browseSource: Error: Cannot list category items for local: TypeError: Cannot read properties of undefined (reading 'indexOf') Jun 03 11:28:56 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 03 11:28:56 hp4 volumio[1214]: info: TuneIn handleBrowseUri: tunein/location Jun 03 11:28:56 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:28:58 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 03 11:28:58 hp4 volumio[1214]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 Jun 03 11:28:58 hp4 volumio[1214]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 Jun 03 11:28:58 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:00 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 03 11:29:00 hp4 volumio[1214]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101239 Jun 03 11:29:00 hp4 volumio[1214]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101239 Jun 03 11:29:01 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:01 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:06 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 03 11:29:06 hp4 volumio[1214]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 03 11:29:06 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:29:06 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:08 hp4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 03 11:29:08 hp4 dbus-daemon[679]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.26' (uid=0 pid=1986 comm="timedatectl show --property=NTPSynchronized --valu") Jun 03 11:29:08 hp4 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 03 11:29:08 hp4 dbus-daemon[679]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 03 11:29:08 hp4 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 03 11:29:08 hp4 setdatetime-helper.sh[1985]: Time is already synchronized. Jun 03 11:29:08 hp4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 03 11:29:08 hp4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 03 11:29:11 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:29:11 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 11:29:11 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 03 11:29:11 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 11:29:11 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 03 11:29:11 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetBrowseSources Jun 03 11:29:11 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 11:29:24 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:24 hp4 volumio[1214]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 11:29:24 hp4 volumio[1214]: info: CoreStateMachine::ClearQueue Jun 03 11:29:24 hp4 volumio[1214]: info: CoreStateMachine::stop Jun 03 11:29:24 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:24 hp4 volumio[1214]: info: CorePlayQueue::clearPlayQueue Jun 03 11:29:24 hp4 volumio[1214]: info: CorePlayQueue::saveQueue Jun 03 11:29:24 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushQueue Jun 03 11:29:24 hp4 volumio[1214]: info: CoreStateMachine::addQueueItems Jun 03 11:29:24 hp4 volumio[1214]: info: CorePlayQueue::addQueueItems Jun 03 11:29:24 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:24 hp4 volumio[1214]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s14864 Jun 03 11:29:24 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 11:29:35 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushQueue Jun 03 11:29:35 hp4 volumio[1214]: info: CorePlayQueue::saveQueue Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::updateTrackBlock Jun 03 11:29:35 hp4 volumio[1214]: info: CorePlayQueue::getTrackBlock Jun 03 11:29:35 hp4 volumio[1214]: info: CoreCommandRouter::volumioPlay Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::play index 0 Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::stop Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::play index undefined Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:35 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:35 hp4 volumio[1214]: info: CoreStateMachine::startPlaybackTimer Jun 03 11:29:35 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:35 hp4 volumio[1214]: info: [1748939375374] ControllerWebradio::clearAddPlayTrack Jun 03 11:29:35 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 11:29:35 hp4 volumio[1214]: info: sendMpdCommand stop took 6 milliseconds Jun 03 11:29:35 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 11:29:35 hp4 volumio[1214]: info: Jun 03 11:29:35 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:35 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:35 hp4 volumio[1214]: info: sendMpdCommand clear took 4 milliseconds Jun 03 11:29:35 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand load "https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf" Jun 03 11:29:35 hp4 volumio[1214]: info: Jun 03 11:29:35 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:35 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:35 hp4 volumio[1214]: info: Jun 03 11:29:35 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:35 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:36 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::ClearQueue Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::stop Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:36 hp4 volumio[1214]: info: CorePlayQueue::clearPlayQueue Jun 03 11:29:36 hp4 volumio[1214]: info: CorePlayQueue::saveQueue Jun 03 11:29:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushQueue Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::addQueueItems Jun 03 11:29:36 hp4 volumio[1214]: info: CorePlayQueue::addQueueItems Jun 03 11:29:36 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:36 hp4 volumio[1214]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s14864 Jun 03 11:29:36 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 11:29:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushQueue Jun 03 11:29:36 hp4 volumio[1214]: info: CorePlayQueue::saveQueue Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::updateTrackBlock Jun 03 11:29:36 hp4 volumio[1214]: info: CorePlayQueue::getTrackBlock Jun 03 11:29:36 hp4 volumio[1214]: info: CoreCommandRouter::volumioPlay Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::play index 0 Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::stop Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::play index undefined Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:36 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:36 hp4 volumio[1214]: info: CoreStateMachine::startPlaybackTimer Jun 03 11:29:36 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:36 hp4 volumio[1214]: info: [1748939376101] ControllerWebradio::clearAddPlayTrack Jun 03 11:29:36 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 11:29:38 hp4 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 03 11:29:41 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:41 hp4 volumio[1214]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 11:29:41 hp4 volumio[1214]: info: CoreStateMachine::ClearQueue Jun 03 11:29:41 hp4 volumio[1214]: info: CoreStateMachine::stop Jun 03 11:29:41 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 11:29:41 hp4 volumio[1214]: info: CorePlayQueue::clearPlayQueue Jun 03 11:29:41 hp4 volumio[1214]: info: CorePlayQueue::saveQueue Jun 03 11:29:41 hp4 volumio[1214]: info: CoreCommandRouter::volumioPushQueue Jun 03 11:29:41 hp4 volumio[1214]: info: CoreStateMachine::addQueueItems Jun 03 11:29:41 hp4 volumio[1214]: info: CorePlayQueue::addQueueItems Jun 03 11:29:41 hp4 volumio[1214]: info: Preload queue cleared Jun 03 11:29:41 hp4 volumio[1214]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s65168 Jun 03 11:29:41 hp4 volumio[1214]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 11:29:42 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:29:42 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:29:42 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:29:42 hp4 volumio[1214]: info: ------------------------------ 6697ms Jun 03 11:29:42 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf" Jun 03 11:29:42 hp4 volumio[1214]: info: ------------------------------ 6694ms Jun 03 11:29:42 hp4 volumio[1214]: info: ------------------------------ 6692ms Jun 03 11:29:42 hp4 volumio[1214]: info: sendMpdCommand stop took 5981 milliseconds Jun 03 11:29:42 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 11:29:42 hp4 volumio[1214]: info: Jun 03 11:29:42 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:42 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:42 hp4 volumio[1214]: info: Jun 03 11:29:42 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:42 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:42 hp4 volumio[1214]: info: sendMpdCommand add "https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf" took 4 milliseconds Jun 03 11:29:42 hp4 volumio[1214]: info: sendMpdCommand clear took 3 milliseconds Jun 03 11:29:42 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 11:29:42 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand play Jun 03 11:29:42 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand load "https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf" Jun 03 11:29:42 hp4 volumio[1214]: info: Jun 03 11:29:42 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:42 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:42 hp4 volumio[1214]: info: Jun 03 11:29:42 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:42 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:42 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:29:42 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:29:42 hp4 volumio[1214]: info: ------------------------------ 12ms Jun 03 11:29:42 hp4 volumio[1214]: info: ------------------------------ 11ms Jun 03 11:29:42 hp4 volumio[1214]: info: sendMpdCommand play took 5 milliseconds Jun 03 11:29:43 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf" Jun 03 11:29:43 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:29:43 hp4 volumio[1214]: error: updateQueue error: null Jun 03 11:29:43 hp4 volumio[1214]: info: ------------------------------ 1510ms Jun 03 11:29:43 hp4 volumio[1214]: info: ------------------------------ 1509ms Jun 03 11:29:43 hp4 volumio[1214]: info: Jun 03 11:29:43 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:43 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:43 hp4 volumio[1214]: info: Jun 03 11:29:43 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:43 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:43 hp4 volumio[1214]: info: Jun 03 11:29:43 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:43 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:43 hp4 volumio[1214]: info: sendMpdCommand add "https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf" took 5 milliseconds Jun 03 11:29:43 hp4 volumio[1214]: info: ------------------------------ 4ms Jun 03 11:29:43 hp4 volumio[1214]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 11:29:43 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand play Jun 03 11:29:43 hp4 volumio[1214]: info: Jun 03 11:29:43 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:43 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:43 hp4 volumio[1214]: info: ------------------------------ 6ms Jun 03 11:29:43 hp4 volumio[1214]: info: ------------------------------ 5ms Jun 03 11:29:43 hp4 volumio[1214]: info: sendMpdCommand play took 3 milliseconds Jun 03 11:29:43 hp4 volumio[1214]: info: ------------------------------ 2ms Jun 03 11:29:43 hp4 volumio[1214]: info: Jun 03 11:29:43 hp4 volumio[1214]: ---------------------------- MPD announces system playlist update Jun 03 11:29:43 hp4 volumio[1214]: info: Ignoring MPD Status Update Jun 03 11:29:43 hp4 volumio[1214]: info: ------------------------------ 1ms Jun 03 11:29:45 hp4 volumio[1214]: info: Jun 03 11:29:45 hp4 volumio[1214]: ---------------------------- MPD announces state update: player Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::getState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Jun 03 11:29:45 hp4 volumio[1214]: info: Jun 03 11:29:45 hp4 volumio[1214]: ---------------------------- MPD announces state update: player Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::getState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Jun 03 11:29:45 hp4 volumio[1214]: info: Jun 03 11:29:45 hp4 volumio[1214]: ---------------------------- MPD announces state update: player Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::getState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Jun 03 11:29:45 hp4 volumio[1214]: info: Jun 03 11:29:45 hp4 volumio[1214]: ---------------------------- MPD announces state update: player Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand status took 6 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand status took 6 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand status took 5 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::getState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 11:29:45 hp4 volumio[1214]: info: Jun 03 11:29:45 hp4 volumio[1214]: ---------------------------- MPD announces state update: player Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::getState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Jun 03 11:29:45 hp4 volumio[1214]: info: Jun 03 11:29:45 hp4 volumio[1214]: ---------------------------- MPD announces state update: player Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::getState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand status Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand status took 8 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand status took 5 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand status took 3 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseTrackInfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseTrackInfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseTrackInfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseState Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CoreCommandRouter::servicePushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","artist":null,"album":null,"uri":"https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","trackType":""} Jun 03 11:29:45 hp4 volumio[1214]: verbose: CURRENT POSITION 0 Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState stateService play Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState currentStatus stop Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CoreCommandRouter::servicePushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","artist":null,"album":null,"uri":"https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","trackType":""} Jun 03 11:29:45 hp4 volumio[1214]: verbose: CURRENT POSITION 0 Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState stateService play Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState currentStatus play Jun 03 11:29:45 hp4 volumio[1214]: info: Received an update from plugin. extracting info from payload Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CoreCommandRouter::servicePushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","artist":null,"album":null,"uri":"https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","trackType":""} Jun 03 11:29:45 hp4 volumio[1214]: verbose: CURRENT POSITION 0 Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState stateService play Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState currentStatus play Jun 03 11:29:45 hp4 volumio[1214]: info: Received an update from plugin. extracting info from payload Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: info: ------------------------------ 44ms Jun 03 11:29:45 hp4 volumio[1214]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'name') Jun 03 11:29:45 hp4 volumio[1214]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'name') Jun 03 11:29:45 hp4 volumio[1214]: info: ------------------------------ 44ms Jun 03 11:29:45 hp4 volumio[1214]: info: ------------------------------ 43ms Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseTrackInfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseTrackInfo Jun 03 11:29:45 hp4 volumio[1214]: verbose: ControllerMpd::parseTrackInfo Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CoreCommandRouter::servicePushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","artist":null,"album":null,"uri":"https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","trackType":""} Jun 03 11:29:45 hp4 volumio[1214]: verbose: CURRENT POSITION 0 Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState stateService play Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState currentStatus play Jun 03 11:29:45 hp4 volumio[1214]: info: Received an update from plugin. extracting info from payload Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CoreCommandRouter::servicePushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","artist":null,"album":null,"uri":"https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","trackType":""} Jun 03 11:29:45 hp4 volumio[1214]: verbose: CURRENT POSITION 0 Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState stateService play Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState currentStatus play Jun 03 11:29:45 hp4 volumio[1214]: info: Received an update from plugin. extracting info from payload Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: info: ControllerMpd::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CoreCommandRouter::servicePushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","artist":null,"album":null,"uri":"https://stream.radioplay.fi/radiopori/radiopori_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=rpf","trackType":""} Jun 03 11:29:45 hp4 volumio[1214]: verbose: CURRENT POSITION 0 Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState stateService play Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::syncState currentStatus play Jun 03 11:29:45 hp4 volumio[1214]: info: Received an update from plugin. extracting info from payload Jun 03 11:29:45 hp4 volumio[1214]: info: CoreStateMachine::pushState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'name') Jun 03 11:29:45 hp4 volumio[1214]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'name') Jun 03 11:29:45 hp4 volumio[1214]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'name') Jun 03 11:29:45 hp4 volumio[1214]: info: ------------------------------ 55ms Jun 03 11:29:45 hp4 volumio[1214]: info: ------------------------------ 49ms Jun 03 11:29:45 hp4 volumio[1214]: info: ------------------------------ 47ms Jun 03 11:29:45 hp4 volumio[1214]: info: CoreCommandRouter::volumioGetState Jun 03 11:29:45 hp4 volumio[1214]: info: CorePlayQueue::getTrack 0 Jun 03 11:29:45 hp4 volumio[1214]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:29:45 hp4 volumio[1214]: TypeError: Cannot read properties of undefined (reading 'name') Jun 03 11:29:45 hp4 volumio[1214]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 03 11:29:45 hp4 volumio[1214]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 03 11:29:45 hp4 volumio[1214]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Jun 03 11:29:45 hp4 volumio[1214]: at Socket.emit (node:events:514:28) Jun 03 11:29:45 hp4 volumio[1214]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 03 11:29:45 hp4 volumio[1214]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 03 11:29:45 hp4 volumio[1214]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 11:29:45 hp4 sudo[2227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-03 11:28' Jun 03 11:29:45 hp4 sudo[2227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a42e8353c03f8616e137cf760ea618e88c395461" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 2 16:46:30 UTC 2025" VOLUMIO_VERSION="4.010" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c04e8f20034852facba163ba7f26248a"