-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-01-16 23:33:42 CET. -- Jan 16 23:33:03 volumio3b systemd-timedated[7745]: Changed local time to Thu Jan 16 23:33:03 2025 Jan 16 23:33:03 volumio3b sudo[7743]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:03 volumio3b volumio-time-update[558]: volumio-time-update-util: System time updated successfully. Jan 16 23:33:03 volumio3b volumio[839]: info: Discovery: A device disappeared from network Jan 16 23:33:03 volumio3b volumio[839]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 16 23:33:03 volumio3b systemd[1]: Starting Daily apt download activities... Jan 16 23:33:03 volumio3b systemd[1]: Started Volumio Time Update Utility. Jan 16 23:33:03 volumio3b systemd[1]: Reached target Multi-User System. Jan 16 23:33:03 volumio3b systemd[1]: Reached target Graphical Interface. Jan 16 23:33:03 volumio3b systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 16 23:33:03 volumio3b systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 16 23:33:03 volumio3b systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 16 23:33:03 volumio3b volumio[839]: info: Discovery: adding bfc09681-6ab8-4b3f-9e3d-9de3400ae355 Jan 16 23:33:03 volumio3b volumio[839]: info: Discovery: Found device Volumio3b Jan 16 23:33:03 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:03 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:04 volumio3b ntpd[740]: Listen normally on 4 wlan0 192.168.1.79:123 Jan 16 23:33:04 volumio3b ntpd[740]: new interface(s) found: waking up resolver Jan 16 23:33:05 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:05 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:05 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:05 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:05 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:05 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.880265, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 16 23:33:05 volumio3b nmbd[7382]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.880768, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 16 23:33:05 volumio3b nmbd[7382]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.880882, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jan 16 23:33:05 volumio3b nmbd[7382]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.880997, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 16 23:33:05 volumio3b nmbd[7382]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.881075, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jan 16 23:33:05 volumio3b nmbd[7382]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.881170, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 16 23:33:05 volumio3b nmbd[7382]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.881258, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jan 16 23:33:05 volumio3b nmbd[7382]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.881348, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 16 23:33:05 volumio3b nmbd[7382]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.881418, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jan 16 23:33:05 volumio3b nmbd[7382]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.881489, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jan 16 23:33:05 volumio3b nmbd[7382]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jan 16 23:33:05 volumio3b nmbd[7382]: [2025/01/16 23:33:05.881559, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jan 16 23:33:05 volumio3b nmbd[7382]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jan 16 23:33:05 volumio3b systemd[1]: apt-daily.service: Succeeded. Jan 16 23:33:05 volumio3b systemd[1]: Started Daily apt download activities. Jan 16 23:33:05 volumio3b systemd[1]: Starting Daily apt upgrade and clean activities... Jan 16 23:33:06 volumio3b systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 16 23:33:06 volumio3b systemd[1]: Started Daily apt upgrade and clean activities. Jan 16 23:33:06 volumio3b systemd[1]: Startup finished in 13.921s (kernel) + 31min 13.997s (userspace) = 31min 27.918s. Jan 16 23:33:07 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jan 16 23:33:07 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jan 16 23:33:07 volumio3b sudo[7866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 16 23:33:07 volumio3b sudo[7866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:07 volumio3b sudo[7866]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:07 volumio3b sudo[7869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 16 23:33:07 volumio3b sudo[7869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:07 volumio3b sudo[7869]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 16 23:33:10 volumio3b volumio[839]: info: Adding plugin bluetooth to MyMusic Plugins Jan 16 23:33:10 volumio3b volumio[839]: info: Adding plugin multiroom to MyMusic Plugins Jan 16 23:33:10 volumio3b volumio[839]: info: Adding plugin metavolumio to MyMusic Plugins Jan 16 23:33:10 volumio3b volumio[839]: info: Adding plugin cd_controller to MyMusic Plugins Jan 16 23:33:10 volumio3b volumio[839]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 16 23:33:10 volumio3b volumio[839]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 16 23:33:10 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 16 23:33:11 volumio3b volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 16 23:33:11 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 16 23:33:11 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:11 volumio3b volumio[839]: info: Starting MyVolumio Remote Streaming Endpoints Jan 16 23:33:11 volumio3b volumio[839]: info: MyVolumio not started Jan 16 23:33:11 volumio3b volumio[839]: info: Initializing device activation check Jan 16 23:33:11 volumio3b volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 16 23:33:11 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 16 23:33:12 volumio3b volumio[839]: info: Starting Streaming Service Transparent Proxy Jan 16 23:33:12 volumio3b volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 16 23:33:12 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 16 23:33:12 volumio3b volumio[839]: info: Streaming services startup Jan 16 23:33:12 volumio3b volumio[839]: info: Starting Streaming Daemon Jan 16 23:33:12 volumio3b sudo[7894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 16 23:33:12 volumio3b sudo[7894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:13 volumio3b volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 16 23:33:13 volumio3b sudo[7894]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jan 16 23:33:13 volumio3b volumio[839]: info: Refreshing Cached IP Addresses Jan 16 23:33:13 volumio3b sudo[7902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jan 16 23:33:13 volumio3b sudo[7902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:13 volumio3b sudo[7902]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:13 volumio3b sudo[7904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 16 23:33:13 volumio3b sudo[7904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:13 volumio3b sudo[7904]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:13 volumio3b volumio[839]: info: Volumio Network Manager: Network status updated: 2 Jan 16 23:33:13 volumio3b sudo[7907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 16 23:33:13 volumio3b sudo[7907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:13 volumio3b volumio[839]: error: Cannot start Volumio Streaming Daemon Jan 16 23:33:13 volumio3b volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 16 23:33:13 volumio3b volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 16 23:33:13 volumio3b sudo[7907]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:13 volumio3b systemd[1]: upmpdcli.service: Succeeded. Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:13 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:13 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:13 volumio3b volumio[839]: verbose: New Socket.io Connection to 192.168.1.79:3000 from 192.168.1.63 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:13 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:13 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:13 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:13 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:14 volumio3b volumio[839]: STREAMING PROXY: Starting server on port 3245 Jan 16 23:33:14 volumio3b volumio[839]: Node JS runtime: 14 Jan 16 23:33:17 volumio3b sudo[7914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 16 23:33:17 volumio3b sudo[7914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:17 volumio3b sudo[7914]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:17 volumio3b sudo[7916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 16 23:33:17 volumio3b sudo[7916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:17 volumio3b sudo[7916]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:17 volumio3b volumio[839]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.63 UA: Mozilla/5.0 (Linux; Android 14; 23030RAC7Y Build/UP1A.230905.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.260 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 16 23:33:17 volumio3b sudo[7920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 16 23:33:17 volumio3b sudo[7920]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:17 volumio3b sudo[7920]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:17 volumio3b sudo[7922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 16 23:33:17 volumio3b sudo[7922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:17 volumio3b sudo[7922]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:17 volumio3b volumio[839]: verbose: New Socket.io Connection to 192.168.1.79 from 192.168.1.63 UA: Mozilla/5.0 (Linux; Android 14; 23030RAC7Y Build/UP1A.230905.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.260 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:18 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 16 23:33:18 volumio3b volumio[839]: info: Received Get System Info Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:18 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:18 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:18 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:18 volumio3b volumio[839]: info: Listing playlists Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 16 23:33:18 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 16 23:33:19 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 16 23:33:19 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 16 23:33:19 volumio3b volumio[839]: info: Received Get System Info Jan 16 23:33:19 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:19 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:19 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:19 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:19 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:19 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:20 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 16 23:33:20 volumio3b volumio[839]: info: MyVolumio login type: Token Jan 16 23:33:20 volumio3b wpa_supplicant[7567]: RRM: Ignoring radio measurement request: Not RRM network Jan 16 23:33:20 volumio3b volumio[839]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 16 23:33:21 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 16 23:33:21 volumio3b volumio[839]: info: Received Get System Info Jan 16 23:33:21 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:21 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:21 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:21 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:21 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:21 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:22 volumio3b wpa_supplicant[7567]: RRM: Ignoring radio measurement request: Not RRM network Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 16 23:33:23 volumio3b volumio[839]: info: Received Get System Info Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:23 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:23 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:23 volumio3b sudo[7942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 16 23:33:23 volumio3b sudo[7942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:23 volumio3b sudo[7942]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:23 volumio3b sudo[7945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 16 23:33:23 volumio3b sudo[7945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:23 volumio3b sudo[7945]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:23 volumio3b sudo[7948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 16 23:33:23 volumio3b sudo[7948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:23 volumio3b systemd[1]: Started UPnP Renderer front-end to MPD. Jan 16 23:33:23 volumio3b sudo[7948]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:23 volumio3b volumio[839]: info: Upmpdcli Daemon Started Jan 16 23:33:23 volumio3b volumio[7950]: writing RSA key Jan 16 23:33:23 volumio3b volumio[839]: info: MyVolumio token set successfully Jan 16 23:33:23 volumio3b volumio[839]: info: MYVOLUMIO: Adding device Jan 16 23:33:23 volumio3b volumio[839]: info: MYVOLUMIO: Evaluating Server Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 16 23:33:23 volumio3b volumio[839]: info: Received Get System Info Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:23 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:23 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:23 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:24 volumio3b volumio[839]: info: MyVolumio Plan changed: premium Jan 16 23:33:24 volumio3b volumio[839]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 16 23:33:24 volumio3b volumio[839]: info: Removing browser output: myVolumio user plan is not superstar Jan 16 23:33:24 volumio3b volumio[839]: info: Removing audio output: Jan 16 23:33:24 volumio3b volumio[839]: info: MYVOLUMIO: Adding device Jan 16 23:33:24 volumio3b volumio[839]: info: MYVOLUMIO: Evaluating Server Jan 16 23:33:24 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Jan 16 23:33:24 volumio3b volumio[839]: info: Remote config written successfully Jan 16 23:33:24 volumio3b volumio[839]: info: Starting Tunnel 1 Jan 16 23:33:24 volumio3b volumio[839]: info: Starting Tunnel Connection Checker Jan 16 23:33:24 volumio3b volumio[839]: info: MYVolumio Device enabled Jan 16 23:33:24 volumio3b volumio[839]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 16 23:33:24 volumio3b volumio[839]: info: MyVolumio status changed Jan 16 23:33:24 volumio3b volumio[839]: info: Streaming services startup Jan 16 23:33:24 volumio3b volumio[839]: info: Starting Streaming Daemon Jan 16 23:33:24 volumio3b sudo[8042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 16 23:33:24 volumio3b sudo[8042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:24 volumio3b volumio[839]: info: Setting Geolocation for MyVolumio to eu6 Jan 16 23:33:24 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:24 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:24 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:24 volumio3b sudo[8042]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:24 volumio3b volumio[839]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Jan 16 23:33:24 volumio3b volumio[839]: error: Cannot start Volumio Streaming Daemon Jan 16 23:33:24 volumio3b volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 16 23:33:24 volumio3b volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 16 23:33:24 volumio3b volumio[839]: info: Setting Geolocation for MyVolumio to eu10 Jan 16 23:33:24 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:24 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:24 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:24 volumio3b volumio[839]: info: Successfully Added MyVolumio device Jan 16 23:33:25 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 16 23:33:25 volumio3b volumio[839]: info: [1737066805361] Starting BluetoothController Jan 16 23:33:25 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 16 23:33:27 volumio3b sudo[8050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 16 23:33:27 volumio3b sudo[8050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:27 volumio3b sudo[8050]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: MultiRoom plugin initialized Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: STOPPING SNAPCLIENT Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: Snap server stop Jan 16 23:33:27 volumio3b sudo[8066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 16 23:33:27 volumio3b sudo[8066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: STOPPING volumioStreaming Jan 16 23:33:27 volumio3b sudo[8069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 16 23:33:27 volumio3b sudo[8069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:27 volumio3b sudo[8066]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:27 volumio3b sudo[8072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 16 23:33:27 volumio3b sudo[8072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 16 23:33:27 volumio3b sudo[8075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 16 23:33:27 volumio3b sudo[8075]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:27 volumio3b sudo[8069]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:27 volumio3b sudo[8075]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:27 volumio3b sudo[8072]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 16 23:33:27 volumio3b volumio[839]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 16 23:33:27 volumio3b volumio[839]: info: Preparing to generate the ALSA configuration file Jan 16 23:33:27 volumio3b volumio[839]: info: Updating MyVolumio device info Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:27 volumio3b volumio[839]: info: Updating MyVolumio device info Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 16 23:33:27 volumio3b volumio[839]: info: Successfully Added MyVolumio device Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 16 23:33:27 volumio3b volumio[839]: info: Retrieving Cloud Streaming UI Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: Removed streaming files Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: volumioStreaming STOPPED Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: SNAPSERVER STOPPED Jan 16 23:33:27 volumio3b volumio[839]: info: MRS: SNAPCLIENT STOPPED Jan 16 23:33:27 volumio3b volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 16 23:33:27 volumio3b volumio[839]: info: Asound.conf file unchanged, so no further update is needed Jan 16 23:33:27 volumio3b volumio[839]: info: Output device has changed, restarting MPD Jan 16 23:33:27 volumio3b sudo[8078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 16 23:33:27 volumio3b sudo[8078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:27 volumio3b volumio[839]: info: Output device has changed, restarting Shairport Sync Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:27 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:27 volumio3b sudo[8078]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:27 volumio3b sudo[8081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 16 23:33:27 volumio3b sudo[8081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:27 volumio3b systemd[1]: Stopping Music Player Daemon... Jan 16 23:33:27 volumio3b systemd[1]: mpd.service: Succeeded. Jan 16 23:33:27 volumio3b systemd[1]: Stopped Music Player Daemon. Jan 16 23:33:28 volumio3b volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 16 23:33:28 volumio3b volumio[839]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 16 23:33:28 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output for this device Jan 16 23:33:28 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output Jan 16 23:33:28 volumio3b sudo[8090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio3b Jan 16 23:33:28 volumio3b sudo[8090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:28 volumio3b systemd[1]: Starting Music Player Daemon... Jan 16 23:33:28 volumio3b volumio[839]: info: Adding audio output: Jan 16 23:33:28 volumio3b volumio[839]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b sudo[8090]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 16 23:33:28 volumio3b volumio[839]: info: Adding METAVOLUMIO REST API Endpoints Jan 16 23:33:28 volumio3b volumio[839]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 16 23:33:28 volumio3b volumio[839]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 16 23:33:28 volumio3b volumio[839]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 16 23:33:28 volumio3b volumio[839]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 16 23:33:28 volumio3b volumio[839]: info: Preparing CD Folders Jan 16 23:33:28 volumio3b volumio[839]: info: Adding CD REST API Endpoints Jan 16 23:33:28 volumio3b volumio[839]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 16 23:33:28 volumio3b volumio[839]: info: Starting UDEV Watcher for CD Jan 16 23:33:28 volumio3b volumio[839]: info: Detecting CD presence with UDEV Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 16 23:33:28 volumio3b volumio[839]: info: Adding inputs REST Endpoints Jan 16 23:33:28 volumio3b volumio[839]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 16 23:33:28 volumio3b volumio[839]: info: Scanning Audio Inputs Jan 16 23:33:28 volumio3b sudo[8086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 16 23:33:28 volumio3b sudo[8086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:28 volumio3b volumio[839]: info: Checking against Known Cards name Jan 16 23:33:28 volumio3b volumio[839]: info: Adding Server instance for streaming Jan 16 23:33:28 volumio3b sudo[8086]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 16 23:33:28 volumio3b volumio[839]: error: Hi Res Audio Failed Login: Missing Login Data Jan 16 23:33:28 volumio3b volumio[839]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 16 23:33:28 volumio3b volumio[839]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 16 23:33:28 volumio3b volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 16 23:33:28 volumio3b sudo[8100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 16 23:33:28 volumio3b sudo[8100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:28 volumio3b volumio[839]: info: Stopping AccessToken refresher cron Jan 16 23:33:28 volumio3b sudo[8100]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:28 volumio3b volumio[839]: info: AccessToken refresher cron started Jan 16 23:33:28 volumio3b volumio[839]: info: Stopping AccessToken refresher cron for QOBUZ Jan 16 23:33:28 volumio3b volumio[839]: info: AccessToken refresher cron started for QOBUZ Jan 16 23:33:28 volumio3b volumio[839]: info: Adding TIDAL REST API Endpoints Jan 16 23:33:28 volumio3b volumio[839]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 16 23:33:28 volumio3b volumio[839]: info: Adding QOBUZ REST API Endpoints Jan 16 23:33:28 volumio3b volumio[839]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 16 23:33:28 volumio3b volumio[839]: info: Getting Tidal Cloud Configuration Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 16 23:33:28 volumio3b volumio[839]: info: Getting Qobuz Cloud Configuration Jan 16 23:33:28 volumio3b volumio[839]: info: Asking plugin for UI Config Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 16 23:33:28 volumio3b volumio[839]: info: Getting Spotify Cloud Configuration Jan 16 23:33:28 volumio3b volumio[839]: info: Asking plugin for UI Config Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 16 23:33:28 volumio3b volumio[839]: info: Saving Spotify Acccount Jan 16 23:33:28 volumio3b volumio[839]: info: Got it Jan 16 23:33:28 volumio3b volumio[839]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 16 23:33:28 volumio3b volumio[839]: info: Bluetooth name changed to Volumio3b Jan 16 23:33:28 volumio3b volumio[839]: info: MPD Permissions set Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:28 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: Got Tidal Cloud Configuration Jan 16 23:33:28 volumio3b volumio[839]: info: Got it Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 16 23:33:28 volumio3b volumio[839]: info: Starting Shairport Sync Jan 16 23:33:28 volumio3b sudo[8108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 16 23:33:28 volumio3b volumio[839]: info: Preparing to generate the ALSA configuration file Jan 16 23:33:28 volumio3b sudo[8108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:28 volumio3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 16 23:33:28 volumio3b volumio[839]: info: Asound.conf file unchanged, so no further update is needed Jan 16 23:33:28 volumio3b systemd[1]: shairport-sync.service: Succeeded. Jan 16 23:33:28 volumio3b volumio[839]: info: Output device has changed, restarting MPD Jan 16 23:33:28 volumio3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 16 23:33:28 volumio3b sudo[8112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 16 23:33:28 volumio3b sudo[8112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:28 volumio3b volumio[839]: info: Output device has changed, restarting Shairport Sync Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:28 volumio3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 16 23:33:28 volumio3b sudo[8112]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:28 volumio3b sudo[8115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 16 23:33:28 volumio3b sudo[8115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:28 volumio3b sudo[8108]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:28 volumio3b systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 16 23:33:28 volumio3b systemd[1]: mpd.service: Succeeded. Jan 16 23:33:28 volumio3b systemd[1]: Stopped Music Player Daemon. Jan 16 23:33:28 volumio3b systemd[1]: Starting Music Player Daemon... Jan 16 23:33:28 volumio3b volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 16 23:33:28 volumio3b volumio[839]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: Preparing to generate the ALSA configuration file Jan 16 23:33:28 volumio3b volumio[839]: info: Successfully Updated MyVolumio device Jan 16 23:33:28 volumio3b volumio[839]: info: Successfully Updated MyVolumio device Jan 16 23:33:28 volumio3b volumio[839]: info: MPD Permissions set Jan 16 23:33:28 volumio3b volumio[839]: info: Shairport-Sync Started Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b sudo[8122]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b sudo[8122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:28 volumio3b sudo[8122]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:28 volumio3b volumio[839]: info: Asound.conf file unchanged, so no further update is needed Jan 16 23:33:28 volumio3b volumio[839]: info: Output device has changed, restarting MPD Jan 16 23:33:29 volumio3b sudo[8133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 16 23:33:29 volumio3b sudo[8133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:29 volumio3b volumio[839]: info: Output device has changed, restarting Shairport Sync Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:29 volumio3b sudo[8133]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:29 volumio3b sudo[8136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 16 23:33:29 volumio3b sudo[8136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:29 volumio3b systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 16 23:33:29 volumio3b systemd[1]: mpd.service: Succeeded. Jan 16 23:33:29 volumio3b systemd[1]: Stopped Music Player Daemon. Jan 16 23:33:29 volumio3b volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 16 23:33:29 volumio3b volumio[839]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:29 volumio3b systemd[1]: Starting Music Player Daemon... Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetBrowseSources Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetBrowseSources Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetBrowseSources Jan 16 23:33:29 volumio3b volumio[839]: info: MPD Permissions set Jan 16 23:33:29 volumio3b volumio[839]: info: Starting Shairport Sync Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:29 volumio3b sudo[8145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 16 23:33:29 volumio3b sudo[8145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:29 volumio3b sudo[8141]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 16 23:33:29 volumio3b sudo[8141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:29 volumio3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 16 23:33:29 volumio3b sudo[8141]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:29 volumio3b systemd[1]: shairport-sync.service: Succeeded. Jan 16 23:33:29 volumio3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 16 23:33:29 volumio3b volumio[839]: info: Starting Shairport Sync Jan 16 23:33:29 volumio3b sudo[8158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 16 23:33:29 volumio3b sudo[8158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:29 volumio3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 16 23:33:29 volumio3b sudo[8145]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:29 volumio3b volumio[839]: info: Shairport-Sync Started Jan 16 23:33:29 volumio3b systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 16 23:33:29 volumio3b systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 16 23:33:29 volumio3b systemd[1]: shairport-sync.service: Succeeded. Jan 16 23:33:29 volumio3b systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 16 23:33:29 volumio3b systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 16 23:33:29 volumio3b sudo[8158]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:29 volumio3b volumio[839]: info: Shairport-Sync Started Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 16 23:33:29 volumio3b volumio[839]: error: MPD error: Error: This socket has been ended by the other party Jan 16 23:33:29 volumio3b volumio[839]: error: This socket has been ended by the other party {"code":"EPIPE"} Jan 16 23:33:29 volumio3b volumio[839]: error: MPD error: Error: This socket has been ended by the other party Jan 16 23:33:29 volumio3b volumio[839]: error: This socket has been ended by the other party {"code":"EPIPE"} Jan 16 23:33:29 volumio3b volumio[839]: error: MPD error: Error: This socket has been ended by the other party Jan 16 23:33:29 volumio3b volumio[839]: error: This socket has been ended by the other party {"code":"EPIPE"} Jan 16 23:33:29 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::Close All Modals sent Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Jan 16 23:33:31 volumio3b mpd[8153]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 16 23:33:31 volumio3b mpd[8153]: output: No 'audio_output' defined in config file Jan 16 23:33:31 volumio3b mpd[8153]: output: Successfully detected a sndio audio device Jan 16 23:33:31 volumio3b mpd[8153]: zeroconf: No global port, disabling zeroconf Jan 16 23:33:31 volumio3b systemd[1]: Started Music Player Daemon. Jan 16 23:33:31 volumio3b sudo[8115]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:31 volumio3b sudo[8136]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:31 volumio3b sudo[8081]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:31 volumio3b sudo[8183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 16 23:33:31 volumio3b sudo[8183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:31 volumio3b systemd[1]: Started Volumio Bluetooth Module. Jan 16 23:33:31 volumio3b sudo[8183]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:31 volumio3b volumio[839]: info: TidalConnect service stoped! Jan 16 23:33:31 volumio3b sudo[8187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 16 23:33:31 volumio3b sudo[8187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:31 volumio3b volumiobt[8186]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 16 23:33:31 volumio3b sudo[8187]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:31 volumio3b volumio[839]: error: MPD error: The expression evaluated to a falsy value: Jan 16 23:33:31 volumio3b volumio[839]: assert.ok(self.idling) Jan 16 23:33:31 volumio3b volumio[839]: error: The expression evaluated to a falsy value: Jan 16 23:33:31 volumio3b volumio[839]: assert.ok(self.idling) Jan 16 23:33:31 volumio3b volumio[839]: error: MPD error: The expression evaluated to a falsy value: Jan 16 23:33:31 volumio3b volumio[839]: assert.ok(self.idling) Jan 16 23:33:31 volumio3b volumio[839]: error: The expression evaluated to a falsy value: Jan 16 23:33:31 volumio3b volumio[839]: assert.ok(self.idling) Jan 16 23:33:31 volumio3b volumio[839]: info: Volumio BT Module successfully started Jan 16 23:33:31 volumio3b volumio[839]: error: updateQueue error: null Jan 16 23:33:31 volumio3b volumio[839]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 16 23:33:31 volumio3b volumio[839]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetBrowseSources Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 16 23:33:31 volumio3b sudo[8199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 16 23:33:31 volumio3b sudo[8199]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:31 volumio3b sudo[8203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 16 23:33:31 volumio3b sudo[8203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:31 volumio3b systemd[1]: Started Volumio Tidal Connect Service. Jan 16 23:33:31 volumio3b sudo[8199]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:31 volumio3b systemd[1]: Started MyVolumio SSH Tunnel. Jan 16 23:33:31 volumio3b sudo[8203]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:31 volumio3b volumio[839]: info: Remote SSH Started Jan 16 23:33:31 volumio3b autossh[8208]: port set to 0, monitoring disabled Jan 16 23:33:31 volumio3b autossh[8208]: starting ssh (count 1) Jan 16 23:33:31 volumio3b autossh[8208]: ssh child pid is 8211 Jan 16 23:33:31 volumio3b volumiossh-tunnel[8207]: Warning: Permanently added '[eu10.myvolumio.org]:2222,[46.101.141.203]:2222' (RSA) to the list of known hosts. Jan 16 23:33:31 volumio3b volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 16 23:33:31 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:31 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: info: Executing endpoint tc_getconfig Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 16 23:33:32 volumio3b vtcs[8201]: STARTING TidalConnect services, version: 1.4.0.34 Jan 16 23:33:32 volumio3b vtcs[8201]: STARTED TidalConnect services. Jan 16 23:33:32 volumio3b volumio[839]: info: Executing endpoint tc_connect Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 16 23:33:32 volumio3b volumio[839]: info: Connecting to TidalConnect Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::servicePushState Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::pushState Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::volumioPushState Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output update for this device Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: 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} Jan 16 23:33:32 volumio3b volumio[839]: verbose: CURRENT POSITION 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::syncState stateService stop Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::syncState currentStatus stop Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::pushState Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::volumioPushState Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output update for this device Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output Jan 16 23:33:32 volumio3b volumio[839]: info: No code Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::pushState Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::volumioPushState Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output update for this device Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::servicePushState Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::pushState Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::volumioPushState Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output update for this device Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: 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} Jan 16 23:33:32 volumio3b volumio[839]: verbose: CURRENT POSITION 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::syncState stateService stop Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::syncState currentStatus stop Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::pushState Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::volumioPushState Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output update for this device Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output Jan 16 23:33:32 volumio3b volumio[839]: info: No code Jan 16 23:33:32 volumio3b volumio[839]: info: CoreStateMachine::pushState Jan 16 23:33:32 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:32 volumio3b volumio[839]: info: CoreCommandRouter::volumioPushState Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output update for this device Jan 16 23:33:32 volumio3b volumio[839]: info: MRS: Pushing multiroomSync output Jan 16 23:33:32 volumio3b sudo[8228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 16 23:33:32 volumio3b sudo[8228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 16 23:33:32 volumio3b volumiobt[8186]: pulseaudio: no process found Jan 16 23:33:32 volumio3b sudo[8228]: pam_unix(sudo:session): session closed for user root Jan 16 23:33:32 volumio3b dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.33' (uid=1000 pid=8232 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 16 23:33:32 volumio3b systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 16 23:33:32 volumio3b volumio[839]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 16 23:33:32 volumio3b dbus-daemon[584]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 16 23:33:32 volumio3b systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 16 23:33:32 volumio3b rtkit-daemon[8233]: Successfully called chroot. Jan 16 23:33:32 volumio3b rtkit-daemon[8233]: Successfully dropped privileges. Jan 16 23:33:32 volumio3b rtkit-daemon[8233]: Successfully limited resources. Jan 16 23:33:32 volumio3b rtkit-daemon[8233]: Canary thread running. Jan 16 23:33:32 volumio3b rtkit-daemon[8233]: Running. Jan 16 23:33:32 volumio3b rtkit-daemon[8233]: Watchdog thread running. Jan 16 23:33:32 volumio3b dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.34' (uid=0 pid=8233 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 16 23:33:32 volumio3b systemd[1]: Starting Authorization Manager... Jan 16 23:33:32 volumio3b volumio[839]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 16 23:33:32 volumio3b polkitd[8237]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 16 23:33:32 volumio3b dbus-daemon[584]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 16 23:33:32 volumio3b systemd[1]: Started Authorization Manager. Jan 16 23:33:33 volumio3b systemd[1]: systemd-timedated.service: Succeeded. Jan 16 23:33:33 volumio3b pulseaudio[8232]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Jan 16 23:33:33 volumio3b pulseaudio[8232]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Jan 16 23:33:33 volumio3b pulseaudio[8232]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Jan 16 23:33:33 volumio3b pulseaudio[8232]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Jan 16 23:33:33 volumio3b volumio[839]: info: MRS: Getting audio outputs on start Jan 16 23:33:33 volumio3b volumio[839]: info: MRS: Requesting all other devices output Jan 16 23:33:33 volumio3b pulseaudio[8232]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 16 23:33:33 volumio3b pulseaudio[8232]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 16 23:33:33 volumio3b volumiobt[8186]: Applying permissions Jan 16 23:33:33 volumio3b volumiobt[8186]: Setting BT discoverable and pairable Jan 16 23:33:33 volumio3b bluetoothd[730]: Endpoint registered: sender=:1.54 path=/MediaEndpoint/A2DPSource Jan 16 23:33:33 volumio3b bluetoothd[730]: Endpoint registered: sender=:1.54 path=/MediaEndpoint/A2DPSink Jan 16 23:33:33 volumio3b pulseaudio[8232]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 16 23:33:33 volumio3b kernel: Bluetooth: RFCOMM TTY layer initialized Jan 16 23:33:33 volumio3b kernel: Bluetooth: RFCOMM socket layer initialized Jan 16 23:33:33 volumio3b kernel: Bluetooth: RFCOMM ver 1.11 Jan 16 23:33:33 volumio3b volumiobt[8186]: Agent registered Jan 16 23:33:33 volumio3b volumiobt[8186]: [bluetooth]# -e power on Jan 16 23:33:33 volumio3b volumiobt[8186]: Invalid command in menu main: -e Jan 16 23:33:33 volumio3b volumiobt[8186]: Jan 16 23:33:33 volumio3b volumiobt[8186]: Use "help" for a list of available commands in a menu. Jan 16 23:33:33 volumio3b volumiobt[8186]: Use "menu " if you want to enter any submenu. Jan 16 23:33:33 volumio3b volumiobt[8186]: Use "back" if you want to return to menu main. Jan 16 23:33:33 volumio3b volumiobt[8186]: [bluetooth]# agent on Jan 16 23:33:33 volumio3b volumiobt[8186]: Agent is already registered Jan 16 23:33:33 volumio3b volumiobt[8186]: [bluetooth]# discoverable on Jan 16 23:33:33 volumio3b volumiobt[8186]: [bluetooth]# pairable on Jan 16 23:33:33 volumio3b volumiobt[8186]: [bluetooth]# agent NoInputNoOutput Jan 16 23:33:33 volumio3b volumiobt[8186]: Agent is already registered Jan 16 23:33:33 volumio3b volumiobt[8186]: [bluetooth]# default-agent Jan 16 23:33:33 volumio3b volumiobt[8186]: [bluetooth]# quit Jan 16 23:33:33 volumio3b volumiobt[8186]: [59B blob data] Jan 16 23:33:33 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 16 23:33:33 volumio3b mpd[8153]: exception: No database Jan 16 23:33:34 volumio3b volumio[839]: info: TidalConnect service started! Jan 16 23:33:36 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 16 23:33:36 volumio3b volumio[839]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 16 23:33:36 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 16 23:33:36 volumio3b volumio[839]: info: Received Get System Version Jan 16 23:33:36 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 16 23:33:36 volumio3b volumio[839]: info: Received Get System Info Jan 16 23:33:36 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 23:33:36 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 23:33:36 volumio3b volumio[839]: info: Discovery: Getting this device information Jan 16 23:33:36 volumio3b volumio[839]: info: CoreCommandRouter::volumioGetState Jan 16 23:33:36 volumio3b volumio[839]: info: CorePlayQueue::getTrack 0 Jan 16 23:33:36 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 23:33:37 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 16 23:33:37 volumio3b mpd[8153]: exception: No database Jan 16 23:33:38 volumio3b ntpd[740]: Soliciting pool server 217.91.44.17 Jan 16 23:33:39 volumio3b ntpd[740]: Soliciting pool server 185.13.148.71 Jan 16 23:33:40 volumio3b ntpd[740]: Soliciting pool server 144.76.59.106 Jan 16 23:33:40 volumio3b ntpd[740]: Soliciting pool server 207.180.217.145 Jan 16 23:33:41 volumio3b ntpd[740]: Soliciting pool server 213.172.105.106 Jan 16 23:33:41 volumio3b ntpd[740]: Soliciting pool server 94.16.122.152 Jan 16 23:33:41 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 16 23:33:41 volumio3b mpd[8153]: exception: No database Jan 16 23:33:41 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 16 23:33:41 volumio3b volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 16 23:33:42 volumio3b ntpd[740]: Soliciting pool server 185.233.107.180 Jan 16 23:33:42 volumio3b ntpd[740]: Soliciting pool server 49.13.14.46 Jan 16 23:33:42 volumio3b volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 23:33:42 volumio3b volumio[839]: Error: dns service error: unknown Jan 16 23:33:42 volumio3b volumio[839]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jan 16 23:33:42 volumio3b volumio[839]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 16 23:33:42 volumio3b volumio[839]: errorCode: -65537 Jan 16 23:33:42 volumio3b volumio[839]: } Jan 16 23:33:42 volumio3b volumio[839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 23:33:42 volumio3b sudo[8282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-16 23:32 Jan 16 23:33:42 volumio3b sudo[8282]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"