May 15 22:26:13 volumiopi ntpd[926]: CLOCK: time stepped by 97920.355769 May 15 22:26:13 volumiopi ntpd[926]: CLOCK: time changed from 2025-05-14 to 2025-05-15 May 15 22:26:13 volumiopi ntpd[926]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 15 22:26:13 volumiopi systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... May 15 22:26:13 volumiopi volumio[1062]: info: Volumio Network Manager: Network status updated: 2 May 15 22:26:13 volumiopi systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 15 22:26:13 volumiopi systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 15 22:26:13 volumiopi systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 15 22:26:13 volumiopi systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 15 22:26:13 volumiopi systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 15 22:26:14 volumiopi systemd[1]: apt-daily-upgrade.service: Deactivated successfully. May 15 22:26:14 volumiopi systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. May 15 22:26:16 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 15 22:26:16 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 15 22:26:16 volumiopi volumio[1062]: info: Refreshing Cached IP Addresses May 15 22:26:16 volumiopi sudo[8690]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 15 22:26:16 volumiopi sudo[8690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:16 volumiopi sudo[8692]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 22:26:16 volumiopi sudo[8692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:16 volumiopi sudo[8690]: pam_unix(sudo:session): session closed for user root May 15 22:26:16 volumiopi sudo[8694]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 22:26:16 volumiopi sudo[8692]: pam_unix(sudo:session): session closed for user root May 15 22:26:16 volumiopi sudo[8694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:16 volumiopi systemd[1]: upmpdcli.service: Deactivated successfully. May 15 22:26:16 volumiopi sudo[8694]: pam_unix(sudo:session): session closed for user root May 15 22:26:16 volumiopi systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 15 22:26:16 volumiopi dbus-daemon[700]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.111' (uid=0 pid=8699 comm="timedatectl show --property=NTPSynchronized --valu") May 15 22:26:16 volumiopi systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 15 22:26:16 volumiopi dbus-daemon[700]: [system] Successfully activated service 'org.freedesktop.timedate1' May 15 22:26:16 volumiopi systemd[1]: Started systemd-timedated.service - Time & Date Service. May 15 22:26:16 volumiopi setdatetime-helper.sh[8698]: Time is not synchronized. Attempting to sync... May 15 22:26:16 volumiopi sudo[8708]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 15 May 2025 20:26:16 GMT#015' May 15 22:26:16 volumiopi sudo[8708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 15 22:26:16 volumiopi setdatetime-helper.sh[8709]: Thu May 15 22:26:16 CEST 2025 May 15 22:26:16 volumiopi sudo[8708]: pam_unix(sudo:session): session closed for user root May 15 22:26:16 volumiopi setdatetime-helper.sh[8698]: Time synchronized successfully. May 15 22:26:16 volumiopi systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 15 22:26:16 volumiopi systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 15 22:26:16 volumiopi systemd-journald[363]: Time jumped backwards, rotating. May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 15 22:26:17 volumiopi volumio[1062]: info: Adding plugin bluetooth to MyMusic Plugins May 15 22:26:17 volumiopi volumio[1062]: info: Adding plugin multiroom to MyMusic Plugins May 15 22:26:17 volumiopi volumio[1062]: info: Adding plugin metavolumio to MyMusic Plugins May 15 22:26:17 volumiopi volumio[1062]: info: Adding plugin cd_controller to MyMusic Plugins May 15 22:26:17 volumiopi volumio[1062]: info: Adding plugin smart_inputs to MyMusic Plugins May 15 22:26:17 volumiopi volumio[1062]: info: Adding plugin tidalconnect to MyMusic Plugins May 15 22:26:17 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 15 22:26:18 volumiopi volumio[1062]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 15 22:26:18 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 15 22:26:18 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:18 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:18 volumiopi volumio[1062]: info: Starting MyVolumio Remote Streaming Endpoints May 15 22:26:18 volumiopi volumio[1062]: info: MyVolumio not started May 15 22:26:18 volumiopi volumio[1062]: info: Initializing device activation check May 15 22:26:18 volumiopi volumio[1062]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 15 22:26:18 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 15 22:26:19 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 15 22:26:19 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 15 22:26:19 volumiopi volumio[1062]: info: Streaming services startup May 15 22:26:19 volumiopi volumio[1062]: info: Starting Streaming Daemon May 15 22:26:19 volumiopi sudo[8712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 22:26:19 volumiopi sudo[8712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:19 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 15 22:26:19 volumiopi sudo[8712]: pam_unix(sudo:session): session closed for user root May 15 22:26:19 volumiopi volumio[1062]: error: Cannot start Volumio Streaming Daemon May 15 22:26:19 volumiopi volumio[1062]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 15 22:26:19 volumiopi volumio[1062]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.147171, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:22 volumiopi nmbd[2998]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148276, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:22 volumiopi nmbd[2998]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148394, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:22 volumiopi nmbd[2998]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148445, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 15 22:26:22 volumiopi nmbd[2998]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148499, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:22 volumiopi nmbd[2998]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148546, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 15 22:26:22 volumiopi nmbd[2998]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148596, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:22 volumiopi nmbd[2998]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148639, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 15 22:26:22 volumiopi nmbd[2998]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148686, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:22 volumiopi nmbd[2998]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148728, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 15 22:26:22 volumiopi nmbd[2998]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148775, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:22 volumiopi nmbd[2998]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable May 15 22:26:22 volumiopi nmbd[2998]: [2025/05/15 22:26:22.148817, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) May 15 22:26:22 volumiopi nmbd[2998]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed May 15 22:26:22 volumiopi sudo[8721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 22:26:22 volumiopi sudo[8721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:22 volumiopi sudo[8723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 22:26:22 volumiopi sudo[8723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:22 volumiopi sudo[8721]: pam_unix(sudo:session): session closed for user root May 15 22:26:22 volumiopi sudo[8723]: pam_unix(sudo:session): session closed for user root May 15 22:26:22 volumiopi volumio[1062]: verbose: New Socket.io Connection to 192.168.178.39 from 192.168.178.35 UA: Mozilla/5.0 (X11; Linux x86_64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 May 15 22:26:23 volumiopi sudo[8728]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 22:26:23 volumiopi sudo[8728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:23 volumiopi sudo[8728]: pam_unix(sudo:session): session closed for user root May 15 22:26:23 volumiopi sudo[8730]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 22:26:23 volumiopi sudo[8730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:23 volumiopi sudo[8730]: pam_unix(sudo:session): session closed for user root May 15 22:26:23 volumiopi volumio[1062]: verbose: New Socket.io Connection to 192.168.178.39 from 192.168.178.35 UA: Mozilla/5.0 (X11; Linux x86_64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 6 May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetVisibleSources May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:23 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 22:26:23 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:23 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:23 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:23 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:23 volumiopi volumio[1062]: info: Listing playlists May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 22:26:23 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 15 22:26:24 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:24 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:24 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 15 22:26:25 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 22:26:25 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:25 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:25 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:25 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:25 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:25 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:25 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:25 volumiopi sudo[8748]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 22:26:25 volumiopi sudo[8748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:25 volumiopi sudo[8748]: pam_unix(sudo:session): session closed for user root May 15 22:26:25 volumiopi sudo[8750]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 22:26:25 volumiopi sudo[8750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:25 volumiopi sudo[8750]: pam_unix(sudo:session): session closed for user root May 15 22:26:25 volumiopi sudo[8753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 15 22:26:25 volumiopi sudo[8753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:25 volumiopi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 15 22:26:25 volumiopi sudo[8753]: pam_unix(sudo:session): session closed for user root May 15 22:26:25 volumiopi volumio[1062]: info: Upmpdcli Daemon Started May 15 22:26:25 volumiopi upmpdcli[8772]: writing RSA key May 15 22:26:25 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 15 22:26:25 volumiopi volumio[1062]: info: MyVolumio login type: Token May 15 22:26:26 volumiopi nmbd[2998]: [2025/05/15 22:26:26.163530, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 15 22:26:26 volumiopi nmbd[2998]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 15 22:26:26 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 22:26:26 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:26 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:26 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:26 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:26 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:26 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:26 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:26 volumiopi volumio[1062]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 15 22:26:27 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 15 22:26:27 volumiopi volumio[1062]: info: MyVolumio login type: Token May 15 22:26:27 volumiopi volumio[1062]: info: MyVolumio token set successfully May 15 22:26:27 volumiopi volumio[1062]: info: MYVOLUMIO: Adding device May 15 22:26:27 volumiopi volumio[1062]: info: MYVOLUMIO: Evaluating Server May 15 22:26:28 volumiopi volumio[1062]: info: MyVolumio Plan changed: superstar May 15 22:26:28 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar May 15 22:26:28 volumiopi volumio[1062]: info: Adding audio output: May 15 22:26:28 volumiopi volumio[1062]: info: MYVOLUMIO: Adding device May 15 22:26:28 volumiopi volumio[1062]: info: MYVOLUMIO: Evaluating Server May 15 22:26:28 volumiopi volumio[1062]: info: Remote config written successfully May 15 22:26:28 volumiopi volumio[1062]: info: Starting Tunnel 1 May 15 22:26:28 volumiopi volumio[1062]: info: Starting Tunnel Connection Checker May 15 22:26:28 volumiopi volumio[1062]: info: MYVolumio Device enabled May 15 22:26:28 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 15 22:26:28 volumiopi volumio[1062]: info: MyVolumio status changed May 15 22:26:28 volumiopi volumio[1062]: info: Streaming services startup May 15 22:26:28 volumiopi volumio[1062]: info: Starting Streaming Daemon May 15 22:26:28 volumiopi sudo[8809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 22:26:28 volumiopi sudo[8809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:28 volumiopi volumio[1062]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' May 15 22:26:28 volumiopi sudo[8809]: pam_unix(sudo:session): session closed for user root May 15 22:26:28 volumiopi volumio[1062]: error: Cannot start Volumio Streaming Daemon May 15 22:26:28 volumiopi volumio[1062]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 15 22:26:28 volumiopi volumio[1062]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 22:26:28 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:28 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:28 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:28 volumiopi volumio[1062]: info: Setting Geolocation for MyVolumio to eu8 May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 22:26:28 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:28 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:28 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:28 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:29 volumiopi volumio[1062]: info: Setting Geolocation for MyVolumio to eu8 May 15 22:26:29 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:29 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:29 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:29 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 15 22:26:29 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 15 22:26:29 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 15 22:26:29 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 15 22:26:30 volumiopi sudo[8813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 15 22:26:30 volumiopi sudo[8813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:30 volumiopi sudo[8813]: pam_unix(sudo:session): session closed for user root May 15 22:26:30 volumiopi volumio[1062]: info: MRS: MultiRoom plugin initialized May 15 22:26:30 volumiopi volumio[1062]: info: MRS: STOPPING SNAPCLIENT May 15 22:26:30 volumiopi volumio[1062]: info: MRS: Snap server stop May 15 22:26:30 volumiopi volumio[1062]: info: MRS: STOPPING volumioStreaming May 15 22:26:30 volumiopi sudo[8830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 15 22:26:30 volumiopi sudo[8830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:30 volumiopi sudo[8832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 15 22:26:30 volumiopi sudo[8832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 15 22:26:30 volumiopi sudo[8835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 15 22:26:30 volumiopi sudo[8835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:30 volumiopi sudo[8838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 15 22:26:30 volumiopi sudo[8838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:30 volumiopi sudo[8838]: pam_unix(sudo:session): session closed for user root May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 15 22:26:30 volumiopi sudo[8830]: pam_unix(sudo:session): session closed for user root May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 15 22:26:30 volumiopi sudo[8835]: pam_unix(sudo:session): session closed for user root May 15 22:26:30 volumiopi sudo[8832]: pam_unix(sudo:session): session closed for user root May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 15 22:26:30 volumiopi volumio[1062]: info: Preparing to generate the ALSA configuration file May 15 22:26:30 volumiopi volumio[1062]: info: Updating MyVolumio device info May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: Updating MyVolumio device info May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 15 22:26:30 volumiopi volumio[1062]: info: Successfully Added MyVolumio device May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 22:26:30 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:30 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:30 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 22:26:30 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:30 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:30 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:30 volumiopi volumio[1062]: info: MRS: Removed streaming files May 15 22:26:30 volumiopi volumio[1062]: info: MRS: volumioStreaming STOPPED May 15 22:26:30 volumiopi volumio[1062]: info: MRS: SNAPSERVER STOPPED May 15 22:26:30 volumiopi volumio[1062]: info: MRS: SNAPCLIENT STOPPED May 15 22:26:30 volumiopi volumio[1062]: info: Asound.conf file unchanged, so no further update is needed May 15 22:26:30 volumiopi volumio[1062]: info: Output device has changed, restarting MPD May 15 22:26:30 volumiopi volumio[1062]: info: Output device has changed, restarting Shairport Sync May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:30 volumiopi sudo[8843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 22:26:30 volumiopi sudo[8843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:30 volumiopi sudo[8845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 22:26:30 volumiopi sudo[8845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:30 volumiopi volumio[1062]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 22:26:30 volumiopi volumio[1062]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 15 22:26:30 volumiopi sudo[8843]: pam_unix(sudo:session): session closed for user root May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 15 22:26:30 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 15 22:26:30 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 15 22:26:30 volumiopi systemd[1]: Stopping mpd.service - Music Player Daemon... May 15 22:26:30 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 15 22:26:30 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 15 22:26:30 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 15 22:26:30 volumiopi systemd[1]: mpd.service: Deactivated successfully. May 15 22:26:30 volumiopi systemd[1]: Stopped mpd.service - Music Player Daemon. May 15 22:26:30 volumiopi systemd[1]: mpd.service: Consumed 1.393s CPU time. May 15 22:26:30 volumiopi systemd[1]: mpd.socket: Deactivated successfully. May 15 22:26:30 volumiopi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 15 22:26:30 volumiopi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 15 22:26:30 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output for this device May 15 22:26:30 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output May 15 22:26:30 volumiopi volumio[1062]: info: Adding audio output: May 15 22:26:30 volumiopi volumio[1062]: info: Adding audio output: May 15 22:26:30 volumiopi volumio[1062]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 15 22:26:30 volumiopi volumio[1062]: info: Adding METAVOLUMIO REST API Endpoints May 15 22:26:30 volumiopi volumio[1062]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 15 22:26:30 volumiopi volumio[1062]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 15 22:26:30 volumiopi volumio[1062]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 15 22:26:30 volumiopi volumio[1062]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 15 22:26:30 volumiopi volumio[1062]: info: Preparing CD Folders May 15 22:26:30 volumiopi volumio[1062]: info: Adding CD REST API Endpoints May 15 22:26:30 volumiopi volumio[1062]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 15 22:26:30 volumiopi volumio[1062]: info: Starting UDEV Watcher for CD May 15 22:26:30 volumiopi volumio[1062]: info: Detecting CD presence with UDEV May 15 22:26:30 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 15 22:26:30 volumiopi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 15 22:26:30 volumiopi systemd[1]: Starting mpd.service - Music Player Daemon... May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 15 22:26:30 volumiopi volumio[1062]: info: Adding inputs REST Endpoints May 15 22:26:30 volumiopi volumio[1062]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 15 22:26:30 volumiopi volumio[1062]: info: Scanning Audio Inputs May 15 22:26:30 volumiopi bluetoothd[699]: Adv Monitor app :1.115 disconnected from D-Bus May 15 22:26:30 volumiopi volumio[1062]: info: Checking against Known Cards name May 15 22:26:30 volumiopi volumio[1062]: info: Adding Server instance for streaming May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 15 22:26:30 volumiopi volumio[1062]: error: Hi Res Audio Failed Login: Missing Login Data May 15 22:26:30 volumiopi volumio[1062]: info: Adding HIGHRESAUDIO REST API Endpoints May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 15 22:26:30 volumiopi volumio[1062]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 15 22:26:30 volumiopi volumio[1062]: info: Stopping AccessToken refresher cron May 15 22:26:30 volumiopi sudo[8865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 15 22:26:30 volumiopi sudo[8865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi volumio[1062]: info: AccessToken refresher cron started May 15 22:26:31 volumiopi volumio[1062]: info: Stopping AccessToken refresher cron for QOBUZ May 15 22:26:31 volumiopi sudo[8860]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 22:26:31 volumiopi sudo[8860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 15 22:26:31 volumiopi sudo[8860]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: AccessToken refresher cron started for QOBUZ May 15 22:26:31 volumiopi volumio[1062]: info: Adding TIDAL REST API Endpoints May 15 22:26:31 volumiopi volumio[1062]: info: Adding QOBUZ REST API Endpoints May 15 22:26:31 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 15 22:26:31 volumiopi volumio[1062]: info: MPD Permissions set May 15 22:26:31 volumiopi volumio[1062]: info: Successfully Updated MyVolumio device May 15 22:26:31 volumiopi sudo[8870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 15 22:26:31 volumiopi sudo[8870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi sudo[8865]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:31 volumiopi systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 15 22:26:31 volumiopi sudo[8870]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::volumioStop May 15 22:26:31 volumiopi volumio[1062]: info: CoreStateMachine::stop May 15 22:26:31 volumiopi volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 22:26:31 volumiopi volumio[1062]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 15 22:26:31 volumiopi volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 22:26:31 volumiopi volumiobt[8880]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 15 22:26:31 volumiopi volumio[1062]: info: Starting Shairport Sync May 15 22:26:31 volumiopi sudo[8881]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 15 22:26:31 volumiopi sudo[8881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi sudo[8881]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: Preparing to generate the ALSA configuration file May 15 22:26:31 volumiopi sudo[8883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 22:26:31 volumiopi sudo[8883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi volumio[1062]: info: Asound.conf file unchanged, so no further update is needed May 15 22:26:31 volumiopi volumio[1062]: info: Output device has changed, restarting MPD May 15 22:26:31 volumiopi sudo[8885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 15 22:26:31 volumiopi sudo[8885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi sudo[8885]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumiobt[8890]: INFO [BTSTART] Powering on Bluetooth if needed... May 15 22:26:31 volumiopi sudo[8888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 22:26:31 volumiopi sudo[8888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 15 22:26:31 volumiopi sudo[8888]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: Output device has changed, restarting Shairport Sync May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi systemd[1]: shairport-sync.service: Deactivated successfully. May 15 22:26:31 volumiopi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 22:26:31 volumiopi systemd[1]: shairport-sync.service: Consumed 1.656s CPU time. May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:31 volumiopi sudo[8895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 22:26:31 volumiopi sudo[8895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi volumio[1062]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 22:26:31 volumiopi bluetoothd[699]: Adv Monitor app :1.116 disconnected from D-Bus May 15 22:26:31 volumiopi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 22:26:31 volumiopi volumiobt[8902]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 15 22:26:31 volumiopi volumio[1062]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi sudo[8883]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: Preparing to generate the ALSA configuration file May 15 22:26:31 volumiopi systemd[1]: mpd.service: Deactivated successfully. May 15 22:26:31 volumiopi systemd[1]: Stopped mpd.service - Music Player Daemon. May 15 22:26:31 volumiopi systemd[1]: mpd.socket: Deactivated successfully. May 15 22:26:31 volumiopi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 15 22:26:31 volumiopi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 15 22:26:31 volumiopi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 15 22:26:31 volumiopi volumiobt[8904]: [75B blob data] May 15 22:26:31 volumiopi volumiobt[8904]: [bluetooth]# pairable on May 15 22:26:31 volumiopi systemd[1]: Starting mpd.service - Music Player Daemon... May 15 22:26:31 volumiopi bluetoothd[699]: Adv Monitor app :1.117 disconnected from D-Bus May 15 22:26:31 volumiopi volumiobt[8904]: [bluetooth]# May 15 22:26:31 volumiopi volumiobt[8923]: INFO [BTSTART] Registering Bluetooth agent... May 15 22:26:31 volumiopi volumio[1062]: info: MPD Permissions set May 15 22:26:31 volumiopi volumio[1062]: info: Shairport-Sync Started May 15 22:26:31 volumiopi bluetoothd[699]: Adv Monitor app :1.120 disconnected from D-Bus May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: Asound.conf file unchanged, so no further update is needed May 15 22:26:31 volumiopi volumio[1062]: info: Output device has changed, restarting MPD May 15 22:26:31 volumiopi volumiobt[8926]: No agent is registered May 15 22:26:31 volumiopi bluetoothd[699]: Adv Monitor app :1.121 disconnected from D-Bus May 15 22:26:31 volumiopi volumiobt[8932]: INFO [BTSTART] Agent registered successfully. May 15 22:26:31 volumiopi volumiobt[8936]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 15 22:26:31 volumiopi volumio[1062]: info: Output device has changed, restarting Shairport Sync May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:31 volumiopi sudo[8920]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 22:26:31 volumiopi sudo[8920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 15 22:26:31 volumiopi sudo[8920]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi sudo[8934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 22:26:31 volumiopi sudo[8934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi sudo[8937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 22:26:31 volumiopi sudo[8937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi sudo[8934]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 22:26:31 volumiopi volumio[1062]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: MPD Permissions set May 15 22:26:31 volumiopi volumio[1062]: info: Starting Shairport Sync May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi sudo[8952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 22:26:31 volumiopi systemd[1]: mpd.service: Deactivated successfully. May 15 22:26:31 volumiopi systemd[1]: Stopped mpd.service - Music Player Daemon. May 15 22:26:31 volumiopi systemd[1]: mpd.socket: Deactivated successfully. May 15 22:26:31 volumiopi sudo[8952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 15 22:26:31 volumiopi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 15 22:26:31 volumiopi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 22:26:31 volumiopi systemd[1]: Starting mpd.service - Music Player Daemon... May 15 22:26:31 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:31 volumiopi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 15 22:26:31 volumiopi systemd[1]: shairport-sync.service: Deactivated successfully. May 15 22:26:31 volumiopi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 22:26:31 volumiopi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 22:26:31 volumiopi sudo[8952]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: Shairport-Sync Started May 15 22:26:31 volumiopi volumio[1062]: info: Starting Shairport Sync May 15 22:26:31 volumiopi sudo[8968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 22:26:31 volumiopi sudo[8968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:31 volumiopi sudo[8960]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 15 22:26:31 volumiopi sudo[8960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 15 22:26:31 volumiopi sudo[8960]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 15 22:26:31 volumiopi systemd[1]: shairport-sync.service: Deactivated successfully. May 15 22:26:31 volumiopi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 22:26:31 volumiopi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 15 22:26:31 volumiopi sudo[8968]: pam_unix(sudo:session): session closed for user root May 15 22:26:31 volumiopi volumio[1062]: info: Shairport-Sync Started May 15 22:26:32 volumiopi volumio[1062]: info: Successfully Updated MyVolumio device May 15 22:26:32 volumiopi volumio[1062]: info: Successfully Added MyVolumio device May 15 22:26:32 volumiopi volumiobt[8938]: INFO:a2dp-agent:Connecting to system bus. May 15 22:26:32 volumiopi volumiobt[8938]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 May 15 22:26:32 volumiopi volumiobt[8938]: INFO:a2dp-agent:Enabling infinite discovery on adapter. May 15 22:26:32 volumiopi volumiobt[8938]: INFO:a2dp-agent:Registering agent. May 15 22:26:32 volumiopi volumiobt[8938]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... May 15 22:26:32 volumiopi volumiobt[8938]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. May 15 22:26:33 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 22:26:33 volumiopi sudo[9002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 15 22:26:33 volumiopi sudo[9002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:33 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 22:26:33 volumiopi systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 15 22:26:33 volumiopi systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 15 22:26:33 volumiopi volumio[1062]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 15 22:26:33 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:33 volumiopi mpd[8980]: 2025-05-15T22:26:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 22:26:33 volumiopi systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 15 22:26:33 volumiopi systemd[1]: Started mpd.service - Music Player Daemon. May 15 22:26:33 volumiopi sudo[8895]: pam_unix(sudo:session): session closed for user root May 15 22:26:33 volumiopi sudo[8937]: pam_unix(sudo:session): session closed for user root May 15 22:26:33 volumiopi wpa_supplicant[8437]: RRM: Ignoring radio measurement request: Not RRM network May 15 22:26:33 volumiopi sudo[9002]: pam_unix(sudo:session): session closed for user root May 15 22:26:33 volumiopi sudo[8845]: pam_unix(sudo:session): session closed for user root May 15 22:26:33 volumiopi autossh[9021]: port set to 0, monitoring disabled May 15 22:26:33 volumiopi autossh[9021]: starting ssh (count 1) May 15 22:26:33 volumiopi autossh[9021]: ssh child pid is 9024 May 15 22:26:33 volumiopi volumio[1062]: info: Remote SSH Started May 15 22:26:33 volumiopi volumio[1062]: error: MPD error: The expression evaluated to a falsy value: May 15 22:26:33 volumiopi volumio[1062]: assert.ok(self.idling) May 15 22:26:33 volumiopi volumio[1062]: error: The expression evaluated to a falsy value: May 15 22:26:33 volumiopi volumio[1062]: assert.ok(self.idling) May 15 22:26:33 volumiopi volumio[1062]: error: MPD error: The expression evaluated to a falsy value: May 15 22:26:33 volumiopi volumio[1062]: assert.ok(self.idling) May 15 22:26:33 volumiopi volumio[1062]: error: The expression evaluated to a falsy value: May 15 22:26:33 volumiopi volumio[1062]: assert.ok(self.idling) May 15 22:26:33 volumiopi volumio[1062]: error: updateQueue error: null May 15 22:26:33 volumiopi volumio[1062]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 15 22:26:33 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:33 volumiopi volumio[1062]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5RR31X9xyXZd0IG0bhrmsmyMnNS2.342c3c398a47e0df435079c1c0592cb8.state.status' May 15 22:26:33 volumiopi volumiossh-tunnel[9024]: Warning: Permanently added '[eu8.myvolumio.org]:2222' (RSA) to the list of known hosts. May 15 22:26:34 volumiopi volumio[1062]: info: TidalConnect service stoped! May 15 22:26:34 volumiopi volumio[1062]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 15 22:26:34 volumiopi volumio[1062]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 15 22:26:34 volumiopi sudo[9034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 15 22:26:34 volumiopi sudo[9034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 15 22:26:34 volumiopi systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 15 22:26:34 volumiopi sudo[9034]: pam_unix(sudo:session): session closed for user root May 15 22:26:34 volumiopi volumio[1062]: info: Executing endpoint tc_getconfig May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 15 22:26:34 volumiopi vtcs[9037]: STARTING TidalConnect services, version: 1.5.0.45 May 15 22:26:34 volumiopi vtcs[9037]: STARTED TidalConnect services. May 15 22:26:34 volumiopi volumio[1062]: info: Executing endpoint tc_connect May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 15 22:26:34 volumiopi volumio[1062]: info: Connecting to TidalConnect May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::servicePushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::pushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::volumioPushState May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output update for this device May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output May 15 22:26:34 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:34 volumiopi volumio[1062]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 15 22:26:34 volumiopi volumio[1062]: verbose: CURRENT POSITION 0 May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::syncState stateService stop May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::pushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::volumioPushState May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output update for this device May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output May 15 22:26:34 volumiopi volumio[1062]: info: No code May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::pushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::volumioPushState May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output update for this device May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::servicePushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::pushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::volumioPushState May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output update for this device May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output May 15 22:26:34 volumiopi volumio[1062]: info: CorePlayQueue::getTrack 0 May 15 22:26:34 volumiopi volumio[1062]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 15 22:26:34 volumiopi volumio[1062]: verbose: CURRENT POSITION 0 May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::syncState stateService stop May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::pushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::volumioPushState May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output update for this device May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output May 15 22:26:34 volumiopi volumio[1062]: info: No code May 15 22:26:34 volumiopi volumio[1062]: info: CoreStateMachine::pushState May 15 22:26:34 volumiopi volumio[1062]: info: CoreCommandRouter::volumioPushState May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output update for this device May 15 22:26:34 volumiopi volumio[1062]: info: MRS: Pushing multiroomSync output May 15 22:26:34 volumiopi volumio[1062]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5RR31X9xyXZd0IG0bhrmsmyMnNS2.342c3c398a47e0df435079c1c0592cb8.state.status' May 15 22:26:34 volumiopi volumio[1062]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5RR31X9xyXZd0IG0bhrmsmyMnNS2.342c3c398a47e0df435079c1c0592cb8.state.status' May 15 22:26:34 volumiopi volumio[1062]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5RR31X9xyXZd0IG0bhrmsmyMnNS2.342c3c398a47e0df435079c1c0592cb8.state.status' May 15 22:26:34 volumiopi volumio[1062]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5RR31X9xyXZd0IG0bhrmsmyMnNS2.342c3c398a47e0df435079c1c0592cb8.state.status' May 15 22:26:35 volumiopi wpa_supplicant[8437]: RRM: Ignoring radio measurement request: Not RRM network May 15 22:26:35 volumiopi volumio[1062]: info: MRS: Getting audio outputs on start May 15 22:26:35 volumiopi volumio[1062]: info: MRS: Requesting all other devices output May 15 22:26:36 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetQueue May 15 22:26:36 volumiopi volumio[1062]: info: CoreStateMachine::getQueue May 15 22:26:36 volumiopi volumio[1062]: info: CorePlayQueue::getQueue May 15 22:26:36 volumiopi volumio[1062]: info: Executing endpoint metavolumio May 15 22:26:36 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 15 22:26:37 volumiopi volumio[1062]: info: TidalConnect service started! May 15 22:26:37 volumiopi wpa_supplicant[8437]: RRM: Ignoring radio measurement request: Not RRM network May 15 22:26:38 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:38 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 22:26:38 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 15 22:26:38 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 15 22:26:38 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 15 22:26:38 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetBrowseSources May 15 22:26:38 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 22:26:39 volumiopi wpa_supplicant[8437]: RRM: Ignoring radio measurement request: Not RRM network May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 22:26:42 volumiopi volumio[1062]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 15 22:26:42 volumiopi volumio[1062]: info: Received Get System Version May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 15 22:26:42 volumiopi volumio[1062]: info: Received Get System Info May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 22:26:42 volumiopi volumio[1062]: info: Discovery: Getting this device information May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::volumioGetState May 15 22:26:42 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 22:26:42 volumiopi volumio[1062]: info: Preload queue cleared May 15 22:26:45 volumiopi volumio[1062]: Searching plugin music_service/webradio May 15 22:26:45 volumiopi volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 15 22:26:45 volumiopi systemd[1]: systemd-timedated.service: Deactivated successfully. May 15 22:26:46 volumiopi volumio[1062]: Use XMLElement.setAttribute instead May 15 22:26:46 volumiopi volumio[1062]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 22:26:46 volumiopi volumio[1062]: TypeError: children[i].attr(...).value is not a function May 15 22:26:46 volumiopi volumio[1062]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 May 15 22:26:46 volumiopi volumio[1062]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 15 22:26:46 volumiopi volumio[1062]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 22:26:46 volumiopi sudo[9084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-15 22:25' May 15 22:26:46 volumiopi sudo[9084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="74e551e797a3a333b0b20739125f2f8b0b974aba" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 14 16:06:36 UTC 2025" VOLUMIO_VERSION="0.066" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="72f9e088cb36b840954a7bc22b48bde7"