-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Thu 2025-03-13 21:12:01 +07. -- Mar 13 21:11:14 volumio-pi systemd-timedated[1031]: Changed local time to Thu Mar 13 21:11:14 2025 Mar 13 21:11:14 volumio-pi sudo[1024]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:14 volumio-pi volumio-time-update[614]: volumio-time-update-util: System time updated successfully. Mar 13 21:11:14 volumio-pi systemd[1]: Started Volumio Time Update Utility. Mar 13 21:11:14 volumio-pi sudo[1047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 21:11:14 volumio-pi sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:14 volumio-pi sudo[1047]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:14 volumio-pi volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 13 21:11:14 volumio-pi volumio[843]: info: [1741875074059] CoreMusicLibrary::Adding element Last_100 Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 13 21:11:14 volumio-pi volumio[843]: info: [1741875074063] CoreMusicLibrary::Adding element Webradio Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 21:11:14 volumio-pi volumio[843]: info: Initializing BBC Radios Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:14 volumio-pi nmbd[777]: [2025/03/13 21:11:14.172862, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 13 21:11:14 volumio-pi systemd[1]: Started Samba NMB Daemon. Mar 13 21:11:14 volumio-pi nmbd[777]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 13 21:11:14 volumio-pi systemd[1]: Starting Samba Winbind Daemon... Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 13 21:11:14 volumio-pi volumio[843]: info: [1741875074192] CoreMusicLibrary::Adding element YouTube2 Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 13 21:11:14 volumio-pi volumio[843]: Cannot find translation for source YouTube2 Mar 13 21:11:14 volumio-pi volumio[843]: info: Volumio Calling Home Mar 13 21:11:14 volumio-pi volumio[843]: info: Discovery: adding 686af361-e09f-4710-8e8d-874c62e3a461 Mar 13 21:11:14 volumio-pi volumio[843]: info: Discovery: Found device Volumio-Pi Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:14 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:14 volumio-pi volumio[843]: info: MPD Permissions set Mar 13 21:11:14 volumio-pi volumio[843]: info: MPD Permissions set Mar 13 21:11:14 volumio-pi volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi winbindd[1063]: [2025/03/13 21:11:14.480139, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 13 21:11:14 volumio-pi winbindd[1063]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 13 21:11:14 volumio-pi winbindd[1063]: [2025/03/13 21:11:14.503047, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 13 21:11:14 volumio-pi systemd[1]: Started Samba Winbind Daemon. Mar 13 21:11:14 volumio-pi winbindd[1063]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi systemd[1]: Starting Samba SMB Daemon... Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:14 volumio-pi volumio[843]: info: Starting Shairport Sync Mar 13 21:11:14 volumio-pi volumio[843]: info: Starting Shairport Sync Mar 13 21:11:14 volumio-pi volumio[843]: info: Starting Shairport Sync Mar 13 21:11:14 volumio-pi sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 21:11:14 volumio-pi sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:14 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 21:11:14 volumio-pi sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 21:11:14 volumio-pi sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:14 volumio-pi sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 21:11:14 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:14 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:14 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Mar 13 21:11:14 volumio-pi sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:14 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 21:11:14 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 21:11:14 volumio-pi sudo[1101]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:14 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 21:11:14 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Mar 13 21:11:14 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 21:11:14 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 21:11:14 volumio-pi sudo[1107]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:14 volumio-pi sudo[1105]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:14 volumio-pi volumio[843]: info: Shairport-Sync Started Mar 13 21:11:14 volumio-pi volumio[843]: Error adding Membership: Error: addMembership EINVAL Mar 13 21:11:14 volumio-pi volumio[843]: info: Shairport-Sync Started Mar 13 21:11:14 volumio-pi volumio[843]: info: Shairport-Sync Started Mar 13 21:11:15 volumio-pi smbd[1095]: [2025/03/13 21:11:15.009498, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 13 21:11:15 volumio-pi smbd[1095]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 13 21:11:15 volumio-pi systemd[1]: Started Samba SMB Daemon. Mar 13 21:11:15 volumio-pi systemd[1]: Reached target Multi-User System. Mar 13 21:11:15 volumio-pi systemd[1]: Reached target Graphical Interface. Mar 13 21:11:15 volumio-pi systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 13 21:11:15 volumio-pi volumio[843]: info: Volumio called home Mar 13 21:11:15 volumio-pi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 13 21:11:15 volumio-pi systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 13 21:11:15 volumio-pi mpd[1052]: Mar 13 21:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 21:11:15 volumio-pi systemd[1]: Started Music Player Daemon. Mar 13 21:11:15 volumio-pi systemd[1]: Startup finished in 12.260s (kernel) + 16.486s (userspace) = 28.746s. Mar 13 21:11:15 volumio-pi sudo[1041]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:15 volumio-pi sudo[1032]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:15 volumio-pi volumio[843]: info: Completed starting Core Plugins Mar 13 21:11:15 volumio-pi volumio[843]: info: ------------------------------------------- Mar 13 21:11:15 volumio-pi volumio[843]: info: ----- MyVolumio plugins startup ---- Mar 13 21:11:15 volumio-pi volumio[843]: info: ------------------------------------------- Mar 13 21:11:15 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 13 21:11:15 volumio-pi volumio[843]: info: MPD running with PID1052 Mar 13 21:11:15 volumio-pi volumio[843]: ,establishing connection Mar 13 21:11:15 volumio-pi volumio[843]: error: MPD error: The expression evaluated to a falsy value: Mar 13 21:11:15 volumio-pi volumio[843]: assert.ok(self.idling) Mar 13 21:11:15 volumio-pi volumio[843]: error: The expression evaluated to a falsy value: Mar 13 21:11:15 volumio-pi volumio[843]: assert.ok(self.idling) Mar 13 21:11:15 volumio-pi volumio[843]: error: MPD error: The expression evaluated to a falsy value: Mar 13 21:11:15 volumio-pi volumio[843]: assert.ok(self.idling) Mar 13 21:11:15 volumio-pi volumio[843]: error: The expression evaluated to a falsy value: Mar 13 21:11:15 volumio-pi volumio[843]: assert.ok(self.idling) Mar 13 21:11:15 volumio-pi volumio[843]: error: updateQueue error: null Mar 13 21:11:19 volumio-pi volumio[843]: info: Volumio Network Manager: Network status updated: 1 Mar 13 21:11:19 volumio-pi sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 13 21:11:19 volumio-pi sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:19 volumio-pi sudo[1146]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:19 volumio-pi sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 13 21:11:19 volumio-pi sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:19 volumio-pi sudo[1148]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:19 volumio-pi sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 13 21:11:19 volumio-pi sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:19 volumio-pi volumio[843]: error: error Mar 13 21:11:19 volumio-pi systemd[1]: Started UPnP Renderer front-end to MPD. Mar 13 21:11:19 volumio-pi sudo[1152]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:19 volumio-pi volumio[843]: info: Upmpdcli Daemon Started Mar 13 21:11:19 volumio-pi volumio[1154]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 13 21:11:20 volumio-pi volumio-remote-updater[635]: [2025-03-13 21:11:20] [connect] Successful connection Mar 13 21:11:20 volumio-pi volumio-remote-updater[635]: [2025-03-13 21:11:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741875080 101 Mar 13 21:11:20 volumio-pi volumio[843]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Mar 13 21:11:20 volumio-pi volumio[1154]: ...........++++ Mar 13 21:11:22 volumio-pi volumio[1154]: ............++++ Mar 13 21:11:22 volumio-pi volumio[1154]: e is 65537 (0x010001) Mar 13 21:11:22 volumio-pi volumio[1154]: writing RSA key Mar 13 21:11:23 volumio-pi sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 13 21:11:23 volumio-pi sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:23 volumio-pi sudo[1186]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:23 volumio-pi sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 13 21:11:23 volumio-pi sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:23 volumio-pi sudo[1188]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:23 volumio-pi volumio[843]: verbose: New Socket.io Connection to 192.168.1.28 from 192.168.1.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:23 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 13 21:11:23 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 13 21:11:23 volumio-pi volumio[843]: info: Adding plugin bluetooth to MyMusic Plugins Mar 13 21:11:23 volumio-pi volumio[843]: info: Adding plugin multiroom to MyMusic Plugins Mar 13 21:11:23 volumio-pi volumio[843]: info: Adding plugin metavolumio to MyMusic Plugins Mar 13 21:11:23 volumio-pi volumio[843]: info: Adding plugin cd_controller to MyMusic Plugins Mar 13 21:11:23 volumio-pi volumio[843]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 13 21:11:23 volumio-pi volumio[843]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 13 21:11:23 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 13 21:11:24 volumio-pi volumio[843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 13 21:11:24 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 13 21:11:24 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:24 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:24 volumio-pi volumio[843]: info: Starting MyVolumio Remote Streaming Endpoints Mar 13 21:11:24 volumio-pi volumio[843]: info: MyVolumio login type: Token Mar 13 21:11:24 volumio-pi volumio[843]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 13 21:11:24 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 13 21:11:25 volumio-pi volumio[843]: info: Starting Streaming Service Transparent Proxy Mar 13 21:11:25 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 13 21:11:25 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 13 21:11:25 volumio-pi volumio[843]: info: Streaming services startup Mar 13 21:11:25 volumio-pi volumio[843]: info: Starting Streaming Daemon Mar 13 21:11:25 volumio-pi sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 13 21:11:25 volumio-pi sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:25 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 13 21:11:25 volumio-pi sudo[1198]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:25 volumio-pi volumio[843]: error: Cannot start Volumio Streaming Daemon Mar 13 21:11:25 volumio-pi volumio[843]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 13 21:11:25 volumio-pi volumio[843]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 13 21:11:25 volumio-pi sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 13 21:11:25 volumio-pi sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:25 volumio-pi sudo[1209]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:26 volumio-pi sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 13 21:11:26 volumio-pi sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:26 volumio-pi sudo[1212]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:26 volumio-pi volumio[843]: verbose: New Socket.io Connection to 192.168.1.28 from 192.168.1.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:26 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 13 21:11:26 volumio-pi volumio[843]: info: Listing playlists Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 13 21:11:26 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 13 21:11:26 volumio-pi volumio[843]: error: MyVolumio Custom Token format not valid, refreshing it Mar 13 21:11:26 volumio-pi volumio[843]: STREAMING PROXY: Starting server on port 3245 Mar 13 21:11:26 volumio-pi volumio[843]: Node JS runtime: 14 Mar 13 21:11:27 volumio-pi volumio[843]: info: MyVolumio login type: Token Mar 13 21:11:27 volumio-pi volumio[843]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 21:11:28 volumio-pi volumio[843]: info: Received Get System Info Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 21:11:28 volumio-pi volumio[843]: info: Discovery: Getting this device information Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:28 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:28 volumio-pi volumio[843]: info: CURURI: music-library Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetQueue Mar 13 21:11:28 volumio-pi volumio[843]: info: CoreStateMachine::getQueue Mar 13 21:11:28 volumio-pi volumio[843]: info: CorePlayQueue::getQueue Mar 13 21:11:28 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:29 volumio-pi volumio[843]: info: MyVolumio token set successfully Mar 13 21:11:29 volumio-pi volumio[843]: info: MYVOLUMIO: Adding device Mar 13 21:11:29 volumio-pi volumio[843]: info: MYVOLUMIO: Evaluating Server Mar 13 21:11:29 volumio-pi volumio[843]: info: MyVolumio Plan changed: premium Mar 13 21:11:29 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 13 21:11:29 volumio-pi volumio[843]: info: Removing browser output: myVolumio user plan is not superstar Mar 13 21:11:29 volumio-pi volumio[843]: info: Removing audio output: Mar 13 21:11:29 volumio-pi volumio[843]: info: MYVOLUMIO: Adding device Mar 13 21:11:29 volumio-pi volumio[843]: info: MYVOLUMIO: Evaluating Server Mar 13 21:11:29 volumio-pi volumio[843]: info: Remote config written successfully Mar 13 21:11:29 volumio-pi volumio[843]: info: Starting Tunnel 1 Mar 13 21:11:29 volumio-pi volumio[843]: info: Starting Tunnel Connection Checker Mar 13 21:11:29 volumio-pi volumio[843]: info: Completed starting MyVolumio Plugin Mar 13 21:11:29 volumio-pi volumio[843]: info: MYVolumio Device enabled Mar 13 21:11:29 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 13 21:11:29 volumio-pi volumio[843]: info: MyVolumio status changed Mar 13 21:11:29 volumio-pi volumio[843]: info: Streaming services startup Mar 13 21:11:29 volumio-pi volumio[843]: info: Starting Streaming Daemon Mar 13 21:11:29 volumio-pi sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 13 21:11:29 volumio-pi sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:29 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 13 21:11:30 volumio-pi sudo[1261]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:30 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 13 21:11:30 volumio-pi volumio[843]: info: [1741875090151] Starting BluetoothController Mar 13 21:11:30 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 13 21:11:31 volumio-pi sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 13 21:11:31 volumio-pi sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi sudo[1264]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: MultiRoom plugin initialized Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: STOPPING SNAPCLIENT Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: Snap server stop Mar 13 21:11:31 volumio-pi sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 13 21:11:31 volumio-pi sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: STOPPING volumioStreaming Mar 13 21:11:31 volumio-pi sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 13 21:11:31 volumio-pi sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi sudo[1280]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:31 volumio-pi sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 13 21:11:31 volumio-pi sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 13 21:11:31 volumio-pi sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 13 21:11:31 volumio-pi sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi sudo[1289]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:31 volumio-pi sudo[1283]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:31 volumio-pi sudo[1286]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 13 21:11:31 volumio-pi systemd[1]: systemd-fsckd.service: Succeeded. Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 13 21:11:31 volumio-pi volumio[843]: info: Preparing to generate the ALSA configuration file Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:31 volumio-pi volumio[843]: info: CURURI: music-library/USB Mar 13 21:11:31 volumio-pi volumio[843]: info: Setting Geolocation for MyVolumio to as1 Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:31 volumio-pi volumio[843]: info: Setting Geolocation for MyVolumio to as1 Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: Removed streaming files Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: volumioStreaming STOPPED Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: SNAPSERVER STOPPED Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: SNAPCLIENT STOPPED Mar 13 21:11:31 volumio-pi volumio[843]: error: Cannot start Volumio Streaming Daemon Mar 13 21:11:31 volumio-pi volumio[843]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 13 21:11:31 volumio-pi volumio[843]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 13 21:11:31 volumio-pi volumio[843]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 13 21:11:31 volumio-pi volumio[843]: info: Reading ALSA contributions from plugins. Mar 13 21:11:31 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:31 volumio-pi volumio[843]: info: Asound.conf file written Mar 13 21:11:31 volumio-pi sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 13 21:11:31 volumio-pi sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi sudo[1292]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:31 volumio-pi volumio[843]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error Mar 13 21:11:31 volumio-pi volumio[843]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Mar 13 21:11:31 volumio-pi volumio[843]: info: Output device has changed, restarting MPD Mar 13 21:11:31 volumio-pi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Mar 13 21:11:31 volumio-pi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 13 21:11:31 volumio-pi sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 21:11:31 volumio-pi sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi sudo[1297]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:31 volumio-pi volumio[843]: info: Output device has changed, restarting Shairport Sync Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:31 volumio-pi sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 21:11:31 volumio-pi sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:31 volumio-pi systemd[1]: Stopping Music Player Daemon... Mar 13 21:11:31 volumio-pi volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 21:11:31 volumio-pi volumio[843]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:31 volumio-pi systemd[1]: mpd.service: Succeeded. Mar 13 21:11:31 volumio-pi systemd[1]: Stopped Music Player Daemon. Mar 13 21:11:31 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 13 21:11:31 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 13 21:11:32 volumio-pi systemd[1]: Starting Music Player Daemon... Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 13 21:11:32 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output for this device Mar 13 21:11:32 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding audio output: Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding audio output: Mar 13 21:11:32 volumio-pi sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-Pi Mar 13 21:11:32 volumio-pi sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:32 volumio-pi sudo[1309]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:32 volumio-pi volumio[843]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding METAVOLUMIO REST API Endpoints Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 13 21:11:32 volumio-pi volumio[843]: info: Preparing CD Folders Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding CD REST API Endpoints Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 13 21:11:32 volumio-pi volumio[843]: info: Starting UDEV Watcher for CD Mar 13 21:11:32 volumio-pi volumio[843]: info: Detecting CD presence with UDEV Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 13 21:11:32 volumio-pi sudo[1305]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 21:11:32 volumio-pi sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 13 21:11:32 volumio-pi sudo[1305]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding inputs REST Endpoints Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 13 21:11:32 volumio-pi volumio[843]: info: Scanning Audio Inputs Mar 13 21:11:32 volumio-pi volumio[843]: info: Checking against Known Cards name Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding Server instance for streaming Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 13 21:11:32 volumio-pi volumio[843]: error: Hi Res Audio Failed Login: Missing Login Data Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 13 21:11:32 volumio-pi volumio[843]: info: Refreshing QOBUZ token Mar 13 21:11:32 volumio-pi volumio[843]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 13 21:11:32 volumio-pi volumio[843]: info: Stopping AccessToken refresher cron Mar 13 21:11:32 volumio-pi sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 21:11:32 volumio-pi sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:32 volumio-pi sudo[1319]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:32 volumio-pi volumio[843]: info: AccessToken refresher cron started Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding TIDAL REST API Endpoints Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 13 21:11:32 volumio-pi volumio[843]: info: Bluetooth name changed to Volumio-Pi Mar 13 21:11:32 volumio-pi volumio[843]: info: MPD Permissions set Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: Starting Shairport Sync Mar 13 21:11:32 volumio-pi sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 21:11:32 volumio-pi sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:32 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 21:11:32 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Mar 13 21:11:32 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 21:11:32 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 21:11:32 volumio-pi sudo[1327]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:32 volumio-pi volumio[843]: info: Shairport-Sync Started Mar 13 21:11:32 volumio-pi volumio[843]: info: Access Token successfully retrieved Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 13 21:11:32 volumio-pi volumio[843]: info: [1741875092575] CoreMusicLibrary::Adding element QOBUZ Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 13 21:11:32 volumio-pi volumio[843]: Cannot find translation for source YouTube2 Mar 13 21:11:32 volumio-pi volumio[843]: Cannot find translation for source QOBUZ Mar 13 21:11:32 volumio-pi volumio[843]: info: Stopping AccessToken refresher cron for QOBUZ Mar 13 21:11:32 volumio-pi volumio[843]: info: AccessToken refresher cron started for QOBUZ Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding QOBUZ REST API Endpoints Mar 13 21:11:32 volumio-pi volumio[843]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 13 21:11:32 volumio-pi volumio[843]: info: Successfully Added MyVolumio device Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:32 volumio-pi volumio[843]: info: CURURI: music-library/USB/Hant Mar 13 21:11:32 volumio-pi volumio[843]: error: MPD error: Error: This socket has been ended by the other party Mar 13 21:11:32 volumio-pi volumio[843]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 13 21:11:32 volumio-pi volumio[843]: error: MPD error: Error: This socket has been ended by the other party Mar 13 21:11:32 volumio-pi volumio[843]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 13 21:11:32 volumio-pi volumio[843]: error: MPD error: Error: This socket has been ended by the other party Mar 13 21:11:32 volumio-pi volumio[843]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 13 21:11:32 volumio-pi volumio[843]: info: Updating MyVolumio device info Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:32 volumio-pi volumio[843]: info: Updating MyVolumio device info Mar 13 21:11:32 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:32 volumio-pi mpd[1315]: Mar 13 21:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 21:11:32 volumio-pi systemd[1]: Started Music Player Daemon. Mar 13 21:11:32 volumio-pi sudo[1300]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:32 volumio-pi volumio[843]: error: updateQueue error: null Mar 13 21:11:33 volumio-pi volumio[843]: info: Successfully Added MyVolumio device Mar 13 21:11:33 volumio-pi volumio[843]: info: Successfully Updated MyVolumio device Mar 13 21:11:33 volumio-pi volumio[843]: info: Successfully Updated MyVolumio device Mar 13 21:11:33 volumio-pi systemd[1]: systemd-hostnamed.service: Succeeded. Mar 13 21:11:34 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 21:11:34 volumio-pi volumio[843]: info: Received Get System Info Mar 13 21:11:34 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 21:11:34 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 21:11:34 volumio-pi volumio[843]: info: Discovery: Getting this device information Mar 13 21:11:34 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:34 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:34 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 21:11:34 volumio-pi sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 13 21:11:34 volumio-pi sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:34 volumio-pi systemd[1]: Started MyVolumio SSH Tunnel. Mar 13 21:11:34 volumio-pi sudo[1343]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:34 volumio-pi volumio[843]: info: Remote SSH Started Mar 13 21:11:34 volumio-pi autossh[1346]: port set to 0, monitoring disabled Mar 13 21:11:34 volumio-pi autossh[1346]: starting ssh (count 1) Mar 13 21:11:34 volumio-pi autossh[1346]: ssh child pid is 1349 Mar 13 21:11:34 volumio-pi volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 13 21:11:34 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:34 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumiossh-tunnel[1345]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Mar 13 21:11:35 volumio-pi sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 13 21:11:35 volumio-pi sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:35 volumio-pi systemd[1]: Started Volumio Bluetooth Module. Mar 13 21:11:35 volumio-pi sudo[1351]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:35 volumio-pi volumio[843]: info: Volumio BT Module successfully started Mar 13 21:11:35 volumio-pi sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 13 21:11:35 volumio-pi sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:35 volumio-pi volumiobt[1353]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 13 21:11:35 volumio-pi sudo[1354]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:35 volumio-pi volumio[843]: info: TidalConnect service stoped! Mar 13 21:11:35 volumio-pi volumio[843]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 21:11:35 volumio-pi volumio[843]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 21:11:35 volumio-pi sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 21:11:35 volumio-pi sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:35 volumio-pi systemd[1]: Started Volumio Tidal Connect Service. Mar 13 21:11:35 volumio-pi sudo[1366]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:35 volumio-pi volumio[843]: info: Executing endpoint tc_getconfig Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 13 21:11:35 volumio-pi vtcs[1368]: STARTING TidalConnect services, version: 1.4.0.34 Mar 13 21:11:35 volumio-pi vtcs[1368]: STARTED TidalConnect services. Mar 13 21:11:35 volumio-pi volumio[843]: info: Executing endpoint tc_connect Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 13 21:11:35 volumio-pi volumio[843]: info: Connecting to TidalConnect Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::servicePushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::pushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::volumioPushState Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output update for this device Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: 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} Mar 13 21:11:35 volumio-pi volumio[843]: verbose: CURRENT POSITION 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::syncState stateService stop Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::syncState currentStatus stop Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::pushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::volumioPushState Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output update for this device Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output Mar 13 21:11:35 volumio-pi volumio[843]: info: No code Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::pushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::volumioPushState Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output update for this device Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::servicePushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::pushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::volumioPushState Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output update for this device Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: 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} Mar 13 21:11:35 volumio-pi volumio[843]: verbose: CURRENT POSITION 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::syncState stateService stop Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::syncState currentStatus stop Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::pushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::volumioPushState Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output update for this device Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output Mar 13 21:11:35 volumio-pi volumio[843]: info: No code Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreStateMachine::pushState Mar 13 21:11:35 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:35 volumio-pi volumio[843]: info: CoreCommandRouter::volumioPushState Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output update for this device Mar 13 21:11:35 volumio-pi volumio[843]: info: MRS: Pushing multiroomSync output Mar 13 21:11:36 volumio-pi sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 13 21:11:36 volumio-pi sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 21:11:36 volumio-pi volumiobt[1353]: pulseaudio: no process found Mar 13 21:11:36 volumio-pi sudo[1387]: pam_unix(sudo:session): session closed for user root Mar 13 21:11:36 volumio-pi dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=1391 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 13 21:11:36 volumio-pi systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 13 21:11:36 volumio-pi volumio[843]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 21:11:36 volumio-pi dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 13 21:11:36 volumio-pi systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 13 21:11:36 volumio-pi rtkit-daemon[1392]: Successfully called chroot. Mar 13 21:11:36 volumio-pi rtkit-daemon[1392]: Successfully dropped privileges. Mar 13 21:11:36 volumio-pi rtkit-daemon[1392]: Successfully limited resources. Mar 13 21:11:36 volumio-pi rtkit-daemon[1392]: Running. Mar 13 21:11:36 volumio-pi rtkit-daemon[1392]: Canary thread running. Mar 13 21:11:36 volumio-pi rtkit-daemon[1392]: Watchdog thread running. Mar 13 21:11:36 volumio-pi dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1392 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 13 21:11:36 volumio-pi systemd[1]: Starting Authorization Manager... Mar 13 21:11:36 volumio-pi volumio[843]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 21:11:36 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:36 volumio-pi volumio[843]: info: CURURI: music-library/USB/Hant Mar 13 21:11:36 volumio-pi polkitd[1395]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 13 21:11:36 volumio-pi dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 13 21:11:36 volumio-pi systemd[1]: Started Authorization Manager. Mar 13 21:11:36 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:36 volumio-pi pulseaudio[1391]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 13 21:11:36 volumio-pi pulseaudio[1391]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 13 21:11:36 volumio-pi volumiobt[1353]: Applying permissions Mar 13 21:11:36 volumio-pi volumiobt[1353]: Setting BT discoverable and pairable Mar 13 21:11:36 volumio-pi bluetoothd[778]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Mar 13 21:11:36 volumio-pi bluetoothd[778]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Mar 13 21:11:36 volumio-pi pulseaudio[1391]: 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 Mar 13 21:11:36 volumio-pi kernel: Bluetooth: RFCOMM TTY layer initialized Mar 13 21:11:36 volumio-pi kernel: Bluetooth: RFCOMM socket layer initialized Mar 13 21:11:36 volumio-pi kernel: Bluetooth: RFCOMM ver 1.11 Mar 13 21:11:36 volumio-pi volumiobt[1353]: [CHG] Controller D8:3A:DD:28:64:44 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [117B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [55B blob data] Mar 13 21:11:36 volumio-pi volumiobt[1353]: [bluetooth]# -e power on Mar 13 21:11:36 volumio-pi volumiobt[1353]: Invalid command in menu main: -e Mar 13 21:11:36 volumio-pi volumiobt[1353]: Mar 13 21:11:36 volumio-pi volumiobt[1353]: Use "help" for a list of available commands in a menu. Mar 13 21:11:36 volumio-pi volumiobt[1353]: Use "menu " if you want to enter any submenu. Mar 13 21:11:36 volumio-pi volumiobt[1353]: Use "back" if you want to return to menu main. Mar 13 21:11:36 volumio-pi volumiobt[1353]: [bluetooth]# agent on Mar 13 21:11:36 volumio-pi volumiobt[1353]: Agent is already registered Mar 13 21:11:36 volumio-pi volumiobt[1353]: [bluetooth]# discoverable on Mar 13 21:11:36 volumio-pi volumiobt[1353]: [bluetooth]# pairable on Mar 13 21:11:36 volumio-pi volumiobt[1353]: [bluetooth]# agent NoInputNoOutput Mar 13 21:11:36 volumio-pi volumiobt[1353]: Agent is already registered Mar 13 21:11:36 volumio-pi volumiobt[1353]: [bluetooth]# default-agent Mar 13 21:11:36 volumio-pi volumiobt[1353]: [bluetooth]# quit Mar 13 21:11:36 volumio-pi volumiobt[1353]: [59B blob data] Mar 13 21:11:37 volumio-pi volumio[843]: info: MRS: Getting audio outputs on start Mar 13 21:11:37 volumio-pi volumio[843]: info: MRS: Requesting all other devices output Mar 13 21:11:38 volumio-pi volumio[843]: info: TidalConnect service started! Mar 13 21:11:38 volumio-pi volumio[843]: [Metrics] CommandRouter: 31s 297.00ms Mar 13 21:11:38 volumio-pi volumio[843]: info: CoreCommandRouter::volumiosetStartupVolume Mar 13 21:11:38 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:38 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:38 volumio-pi volumio[843]: info: CoreCommandRouter::Close All Modals sent Mar 13 21:11:38 volumio-pi volumio[843]: info: CoreCommandRouter::Close All Modals sent Mar 13 21:11:38 volumio-pi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 13 21:11:38 volumio-pi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 13 21:11:39 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 13 21:11:39 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 21:11:39 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 13 21:11:39 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:39 volumio-pi volumio[843]: info: CURURI: music-library Mar 13 21:11:39 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:39 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 13 21:11:39 volumio-pi volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 13 21:11:39 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:39 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:40 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:40 volumio-pi volumio[843]: info: CURURI: music-library/USB Mar 13 21:11:40 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:41 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:41 volumio-pi volumio[843]: info: CURURI: music-library/USB/Hant Mar 13 21:11:41 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:42 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:42 volumio-pi volumio[843]: info: CURURI: music-library/USB/Hant/Data Mar 13 21:11:42 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:43 volumio-pi kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 13 21:11:44 volumio-pi systemd[1]: systemd-timedated.service: Succeeded. Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 13 21:11:44 volumio-pi volumio-remote-updater[635]: No test mode Mar 13 21:11:44 volumio-pi volumio-remote-updater[635]: No alpha test mode Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetBrowseSources Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 13 21:11:44 volumio-pi volumio[843]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 13 21:11:44 volumio-pi volumio[843]: info: Received Get System Version Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 21:11:44 volumio-pi volumio[843]: info: Received Get System Info Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 21:11:44 volumio-pi volumio[843]: info: Discovery: Getting this device information Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::volumioGetState Mar 13 21:11:44 volumio-pi volumio[843]: info: CorePlayQueue::getTrack 0 Mar 13 21:11:44 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 21:11:45 volumio-pi volumio[843]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 13 21:11:45 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 13 21:11:45 volumio-pi volumio[843]: info: BOOT COMPLETED Mar 13 21:11:54 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:54 volumio-pi volumio[843]: info: CURURI: music-library Mar 13 21:11:54 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:55 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:55 volumio-pi volumio[843]: info: CURURI: music-library/USB Mar 13 21:11:55 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:56 volumio-pi volumio[843]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 13 21:11:56 volumio-pi volumio[843]: info: CURURI: music-library/USB/Hant Mar 13 21:11:56 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:57 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:57 volumio-pi volumio[843]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 13 21:11:57 volumio-pi volumio[843]: info: CoreStateMachine::ClearQueue Mar 13 21:11:57 volumio-pi volumio[843]: info: CoreStateMachine::stop Mar 13 21:11:57 volumio-pi volumio[843]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 21:11:57 volumio-pi volumio[843]: info: CorePlayQueue::clearPlayQueue Mar 13 21:11:57 volumio-pi volumio[843]: info: CorePlayQueue::saveQueue Mar 13 21:11:57 volumio-pi volumio[843]: info: CoreCommandRouter::volumioPushQueue Mar 13 21:11:57 volumio-pi volumio[843]: info: CoreStateMachine::addQueueItems Mar 13 21:11:57 volumio-pi volumio[843]: info: CorePlayQueue::addQueueItems Mar 13 21:11:57 volumio-pi volumio[843]: info: Preload queue cleared Mar 13 21:11:57 volumio-pi volumio[843]: info: Adding Item to queue: music-library/USB/Hant/Data Mar 13 21:11:57 volumio-pi volumio[843]: info: Exploding uri music-library/USB/Hant/Data in service mpd Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-agancy-multipurpose-template-pptx-6J5TW3' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-aru-powerpoint-template-GU9KWH' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-baldo-powerpoint-6DLFMJ' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-delta-powerpoint-template-4GK89L' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-eco-powerpoint-RJDJ7J' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-funnel-chart-template-for-powerpoint-presentation-AN32DT' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-ganesha-modern-powerpoint-M5PLPC' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-infographic-solutions-powerpoint-infographic-B366CL' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-latte-powerpoint-2JUM4S' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-madura-powerpoint-template-DL2SHZ' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-milo-powerpoint-EKT8S3' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-niaga-powerpoint-template-3VQYH2' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-power-powerpoint-template-96KBLK' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-powerpoint-templates-2NHR4T' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-powerpoint-templates-FYL755' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-powerpoint-templates-HSSN66' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-powerpoint-templates-STBRRR' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-powerpoint-templates-UBE8Y3' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-prime-powerpoint-template-RLC597' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-real-estate-powerpoint-template-YHTZ3D' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-rote-powerpoint-template-SWZZH9' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-rubick-presentation-template-6BDTZX' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-solar-system-education-presentation-MV9ADB' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/PowerPoint/elements-website-proposal-powerpoint-template-JYQCCD' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/Powerpoint - anh Tuấn Share/01-Powerpoint-Infographics' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/Powerpoint - anh Tuấn Share/02-Keynote-Files-Mac-Users-Only-Infographics' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/Powerpoint - anh Tuấn Share/ecology-slides' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/Powerpoint - anh Tuấn Share/seo-slides' Mar 13 21:11:59 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/My Business/03. Slide Material/Powerpoint - anh Tuấn Share/social-media-slides' Mar 13 21:12:00 volumio-pi volumio[843]: error: Failed to stat '/mnt/USB/Hant/Data/Sách & Truyện- to Kindle /Rai' Mar 13 21:12:00 volumio-pi volumio[843]: info: ALBUMART /albumart?cacheid=855&web=AJ%20Hoge/Effortless%20English/extralarge&path=%2Fmnt%2FUSB%2FHant%2FData%2FEffortless%20English%2F1.%20Original%20English%2F1.01%20A%20Day%20for%20the%20Dead.zip&metadata=false Mar 13 21:12:00 volumio-pi volumio[843]: info: URI /mnt/USB/Hant/Data/Effortless English/1. Original English/1.01 A Day for the Dead.zip Mar 13 21:12:00 volumio-pi volumio[843]: info: ALBUMART /albumart?cacheid=855&web=AJ%20Hoge/Effortless%20English/extralarge&path=%2Fmnt%2FUSB%2FHant%2FData%2FEffortless%20English%2F1.%20Original%20English%2F4.02%20Hitch.zip&metadata=false Mar 13 21:12:00 volumio-pi volumio[843]: info: URI /mnt/USB/Hant/Data/Effortless English/1. Original English/4.02 Hitch.zip Mar 13 21:12:00 volumio-pi volumio[843]: info: ALBUMART /albumart?cacheid=855&web=AJ%20Hoge/Effortless%20English/extralarge&path=%2Fmnt%2FUSB%2FHant%2FData%2FEffortless%20English%2F1.%20Original%20English%2F4.02%20Hitch.zip&metadata=false Mar 13 21:12:00 volumio-pi volumio[843]: info: URI /mnt/USB/Hant/Data/Effortless English/1. Original English/4.02 Hitch.zip Mar 13 21:12:00 volumio-pi volumio[843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 21:12:00 volumio-pi volumio[843]: Error: Unable to resolve or reject the same promise twice Mar 13 21:12:00 volumio-pi volumio[843]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 13 21:12:00 volumio-pi volumio[843]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Mar 13 21:12:00 volumio-pi volumio[843]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 13 21:12:00 volumio-pi volumio[843]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 13 21:12:00 volumio-pi volumio[843]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 13 21:12:00 volumio-pi volumio[843]: at Socket.emit (events.js:315:20) Mar 13 21:12:00 volumio-pi volumio[843]: at addChunk (internal/streams/readable.js:309:12) Mar 13 21:12:00 volumio-pi volumio[843]: at readableAddChunk (internal/streams/readable.js:280:11) Mar 13 21:12:00 volumio-pi volumio[843]: at Socket.Readable.push (internal/streams/readable.js:223:10) Mar 13 21:12:00 volumio-pi volumio[843]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Mar 13 21:12:00 volumio-pi volumio[843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 21:12:01 volumio-pi sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 21:11 Mar 13 21:12:01 volumio-pi sudo[1466]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"