-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Wed 2025-05-07 18:31:53 EEST. -- May 07 18:13:17 volumio systemd-timedated[4049]: Changed local time to Wed May 7 18:13:17 2025 May 07 18:13:17 volumio sudo[4067]: pam_unix(sudo:session): session closed for user root May 07 18:13:17 volumio volumio-time-update[472]: volumio-time-update-util: System time updated successfully. May 07 18:13:17 volumio systemd[1]: Starting Daily apt download activities... May 07 18:13:17 volumio sudo[4071]: pam_unix(sudo:session): session closed for user root May 07 18:13:18 volumio systemd[1]: Started Volumio Time Update Utility. May 07 18:13:18 volumio systemd[1]: Reached target Multi-User System. May 07 18:13:18 volumio systemd[1]: Reached target Graphical Interface. May 07 18:13:18 volumio sudo[4078]: pam_unix(sudo:session): session closed for user root May 07 18:13:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 07 18:13:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 18:13:18 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 07 18:13:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:13:18 volumio volumio[757]: STREAMING PROXY: Starting server on port 3245 May 07 18:13:18 volumio volumio[757]: Node JS runtime: 14 May 07 18:13:18 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 07 18:13:18 volumio volumio[757]: info: MyVolumio token set successfully May 07 18:13:18 volumio volumio[757]: info: MYVOLUMIO: Adding device May 07 18:13:18 volumio volumio[757]: info: MYVOLUMIO: Evaluating Server May 07 18:13:18 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 07 18:13:18 volumio volumio[757]: info: Discovery: A device disappeared from network May 07 18:13:18 volumio volumio[757]: info: Discovery: Device volumio disappeared from network May 07 18:13:18 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:19 volumio volumio[757]: info: Discovery: adding 40f928f2-69e7-417f-b22a-175a50a4831c May 07 18:13:19 volumio volumio[757]: info: Discovery: Found device Volumio May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:19 volumio volumio[757]: info: MyVolumio status changed May 07 18:13:19 volumio volumio[757]: info: Streaming services startup May 07 18:13:19 volumio volumio[757]: info: Starting Streaming Daemon May 07 18:13:19 volumio volumio[757]: info: Removing browser output: myVolumio user plan is not superstar May 07 18:13:19 volumio volumio[757]: info: Removing audio output: May 07 18:13:19 volumio volumio[757]: info: Stoppping Tunnel 1 May 07 18:13:19 volumio sudo[4156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 07 18:13:19 volumio sudo[4156]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:13:19 volumio volumio[757]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 18:13:19 volumio sudo[4159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 07 18:13:19 volumio sudo[4159]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:19 volumio sudo[4156]: pam_unix(sudo:session): session closed for user root May 07 18:13:19 volumio volumio[757]: info: Received Get System Version May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:13:19 volumio sudo[4159]: pam_unix(sudo:session): session closed for user root May 07 18:13:19 volumio volumio[757]: info: Received Get System Info May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:13:19 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:13:19 volumio volumio[757]: info: Remote SSH Stopped May 07 18:13:19 volumio volumio[757]: error: Cannot start Volumio Streaming Daemon May 07 18:13:19 volumio volumio[757]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 07 18:13:19 volumio volumio[757]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 07 18:13:20 volumio volumio[757]: info: Setting Geolocation for MyVolumio to eu3 May 07 18:13:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:20 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:20 volumio systemd[1]: apt-daily.service: Succeeded. May 07 18:13:20 volumio systemd[1]: Started Daily apt download activities. May 07 18:13:20 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 07 18:13:20 volumio volumio[757]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 07 18:13:21 volumio volumio[757]: info: Updating MyVolumio device info May 07 18:13:21 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:21 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 07 18:13:21 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 07 18:13:21 volumio systemd[1]: Startup finished in 28.081s (kernel) + 15min 17.603s (userspace) = 15min 45.685s. May 07 18:13:21 volumio sudo[4258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:13:21 volumio sudo[4258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:21 volumio sudo[4260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:13:21 volumio sudo[4260]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:21 volumio sudo[4260]: pam_unix(sudo:session): session closed for user root May 07 18:13:21 volumio sudo[4258]: pam_unix(sudo:session): session closed for user root May 07 18:13:21 volumio sudo[4266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 07 18:13:21 volumio sudo[4266]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:21 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 07 18:13:21 volumio sudo[4266]: pam_unix(sudo:session): session closed for user root May 07 18:13:21 volumio volumio[757]: info: Upmpdcli Daemon Started May 07 18:13:21 volumio volumio[757]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 07 18:13:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 07 18:13:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:13:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 07 18:13:22 volumio volumio[757]: info: CoreCommandRouter::volumioGetBrowseSources May 07 18:13:22 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:13:24 volumio volumio[757]: info: MYVOLUMIO: Adding device May 07 18:13:24 volumio volumio[757]: info: MYVOLUMIO: Evaluating Server May 07 18:13:25 volumio volumio[757]: error: error May 07 18:13:25 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 07 18:13:25 volumio volumio[757]: info: Preparing to save Alsa Options, stopping services first May 07 18:13:25 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:25 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:25 volumio volumio[757]: info: CoreCommandRouter::volumioPause May 07 18:13:25 volumio volumio[757]: info: CoreStateMachine::pause May 07 18:13:25 volumio volumio[757]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI Out"},"disallowPush":true} May 07 18:13:25 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 18:13:25 volumio volumio[757]: info: Enabling PI HDMI Output May 07 18:13:26 volumio volumio[4268]: writing RSA key May 07 18:13:26 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 18:13:26 volumio volumio[757]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 07 18:13:27 volumio volumio[757]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 07 18:13:27 volumio volumio[757]: info: Disabling external Volume Control May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:27 volumio volumio[757]: info: Preparing to generate the ALSA configuration file May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 07 18:13:27 volumio sudo[4370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 07 18:13:27 volumio sudo[4370]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:27 volumio sudo[4372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 07 18:13:27 volumio sudo[4372]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:27 volumio sudo[4372]: pam_unix(sudo:session): session closed for user root May 07 18:13:27 volumio volumio[757]: info: Setting Geolocation for MyVolumio to eu3 May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:27 volumio volumio[757]: info: Raspberry PI HDMI Output Enabled May 07 18:13:27 volumio volumio[757]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 07 18:13:27 volumio volumio[757]: wlan0 Interface doesn't support scanning : Device or resource busy May 07 18:13:27 volumio volumio[757]: info: Cannot use regular scanning, forcing with ap-force May 07 18:13:27 volumio sudo[4377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store May 07 18:13:27 volumio sudo[4377]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:27 volumio sudo[4380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 07 18:13:27 volumio sudo[4380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:27 volumio sudo[4380]: pam_unix(sudo:session): session closed for user root May 07 18:13:27 volumio volumio[757]: command failed: Device or resource busy (-16) May 07 18:13:27 volumio volumio[757]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 07 18:13:27 volumio volumio[757]: command failed: Device or resource busy (-16) May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:13:27 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:27 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:13:27 volumio volumio[757]: info: VolumeController:: Volume=86 Mute =false May 07 18:13:27 volumio volumio[757]: info: CoreStateMachine::pushState May 07 18:13:27 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::volumioPushState May 07 18:13:27 volumio volumio[757]: info: Asound.conf file unchanged, so no further update is needed May 07 18:13:27 volumio volumio[757]: info: Output device has changed, restarting MPD May 07 18:13:27 volumio sudo[4383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 18:13:27 volumio volumio[757]: info: Output device has changed, restarting Shairport Sync May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:27 volumio sudo[4383]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:27 volumio sudo[4383]: pam_unix(sudo:session): session closed for user root May 07 18:13:27 volumio sudo[4385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 18:13:27 volumio sudo[4385]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:27 volumio systemd[1]: Stopping Music Player Daemon... May 07 18:13:27 volumio volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 18:13:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 07 18:13:28 volumio sudo[4370]: pam_unix(sudo:session): session closed for user root May 07 18:13:34 volumio sudo[4377]: pam_unix(sudo:session): session closed for user root May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 18:13:34 volumio volumio[757]: info: Updating MyVolumio device info May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 07 18:13:34 volumio volumio[757]: info: CoreStateMachine::getcurrentVolume May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::volumioRetrievevolume May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Retrieving Cloud Streaming UI May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Retrieving Cloud Streaming UI May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Retrieving Cloud Streaming UI May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Retrieving Cloud Streaming UI May 07 18:13:34 volumio volumio[757]: info: MPD Permissions set May 07 18:13:34 volumio volumio[757]: info: Alsa Settings successfully stored May 07 18:13:34 volumio systemd[1]: mpd.service: Succeeded. May 07 18:13:34 volumio systemd[1]: Stopped Music Player Daemon. May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:34 volumio systemd[1]: Starting Music Player Daemon... May 07 18:13:34 volumio volumio[757]: info: Getting Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Qobuz Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Spotify Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Saving Spotify Acccount May 07 18:13:34 volumio volumio[757]: info: Got Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 18:13:34 volumio volumio[757]: info: Getting Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Qobuz Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Spotify Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Saving Spotify Acccount May 07 18:13:34 volumio volumio[757]: info: Got Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 18:13:34 volumio volumio[757]: info: Getting Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Qobuz Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Spotify Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Saving Spotify Acccount May 07 18:13:34 volumio volumio[757]: info: Got Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 18:13:34 volumio volumio[757]: info: Getting Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Qobuz Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Getting Spotify Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: info: Saving Spotify Acccount May 07 18:13:34 volumio volumio[757]: info: Got Tidal Cloud Configuration May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: info: Got it May 07 18:13:34 volumio volumio[757]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 18:13:34 volumio volumio[757]: info: VolumeController:: Volume=86 Mute =false May 07 18:13:34 volumio volumio[757]: info: CoreStateMachine::pushState May 07 18:13:34 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::volumioPushState May 07 18:13:34 volumio volumio[757]: info: CoreStateMachine::updateTrackBlock May 07 18:13:34 volumio volumio[757]: info: CorePlayQueue::getTrackBlock May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::volumioRetrievevolume May 07 18:13:34 volumio volumio[757]: info: Starting Shairport Sync May 07 18:13:34 volumio sudo[4423]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 18:13:34 volumio sudo[4423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:34 volumio sudo[4431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 18:13:34 volumio sudo[4431]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:34 volumio volumio[757]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 18:13:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 18:13:34 volumio systemd[1]: shairport-sync.service: Succeeded. May 07 18:13:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 18:13:34 volumio volumio[757]: info: VolumeController:: Volume=86 Mute =false May 07 18:13:34 volumio volumio[757]: info: CoreStateMachine::pushState May 07 18:13:34 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:34 volumio volumio[757]: info: CoreCommandRouter::volumioPushState May 07 18:13:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 18:13:35 volumio sudo[4431]: pam_unix(sudo:session): session closed for user root May 07 18:13:35 volumio volumio[757]: info: Shairport-Sync Started May 07 18:13:35 volumio volumio[757]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 07 18:13:35 volumio volumio[757]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 07 18:13:35 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:35 volumio volumio[757]: info: Retrieving Cloud Streaming UI May 07 18:13:35 volumio volumio[757]: info: Getting Tidal Cloud Configuration May 07 18:13:35 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:35 volumio volumio[757]: info: Getting Qobuz Cloud Configuration May 07 18:13:35 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:35 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:35 volumio volumio[757]: info: Getting Spotify Cloud Configuration May 07 18:13:35 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:35 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:35 volumio volumio[757]: info: Saving Spotify Acccount May 07 18:13:35 volumio volumio[757]: info: Got Tidal Cloud Configuration May 07 18:13:35 volumio volumio[757]: info: Got it May 07 18:13:35 volumio volumio[757]: info: Got it May 07 18:13:35 volumio volumio[757]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 18:13:35 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:35 volumio volumio[757]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 18:13:36 volumio sudo[4423]: pam_unix(sudo:session): session closed for user root May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:37 volumio volumio[757]: info: Retrieving Cloud Streaming UI May 07 18:13:37 volumio volumio[757]: info: Getting Tidal Cloud Configuration May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:37 volumio volumio[757]: info: Getting Qobuz Cloud Configuration May 07 18:13:37 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:37 volumio volumio[757]: info: Getting Spotify Cloud Configuration May 07 18:13:37 volumio volumio[757]: info: Asking plugin for UI Config May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:37 volumio volumio[757]: info: Saving Spotify Acccount May 07 18:13:37 volumio volumio[757]: info: Got Tidal Cloud Configuration May 07 18:13:37 volumio volumio[757]: info: Got it May 07 18:13:37 volumio volumio[757]: info: Got it May 07 18:13:37 volumio volumio[757]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:13:37 volumio volumio[757]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 07 18:13:37 volumio volumio-remote-updater[2861]: No test mode May 07 18:13:37 volumio volumio-remote-updater[2861]: No alpha test mode May 07 18:13:37 volumio volumio[757]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 07 18:13:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 07 18:13:38 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 07 18:13:38 volumio volumio[757]: info: CoreCommandRouter::Reload Ui May 07 18:13:38 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 07 18:13:38 volumio volumio[757]: info: Wizard terminated Successfully May 07 18:13:38 volumio volumio[757]: info: CoreCommandRouter::volumiosetStartupVolume May 07 18:13:38 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:13:38 volumio mpd[4436]: May 07 18:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 18:13:39 volumio systemd[1]: Started Music Player Daemon. May 07 18:13:39 volumio sudo[4385]: pam_unix(sudo:session): session closed for user root May 07 18:13:39 volumio volumio[757]: error: updateQueue error: null May 07 18:13:39 volumio sudo[4444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:13:39 volumio sudo[4444]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:39 volumio sudo[4446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:13:39 volumio sudo[4446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:39 volumio sudo[4446]: pam_unix(sudo:session): session closed for user root May 07 18:13:39 volumio sudo[4444]: pam_unix(sudo:session): session closed for user root May 07 18:13:39 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:13:40 volumio volumio-remote-updater[2861]: No test mode May 07 18:13:40 volumio volumio-remote-updater[2861]: No alpha test mode May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 07 18:13:40 volumio sudo[4453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:13:40 volumio sudo[4453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:40 volumio volumio[757]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 07 18:13:40 volumio sudo[4455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:13:40 volumio sudo[4455]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:13:40 volumio sudo[4455]: pam_unix(sudo:session): session closed for user root May 07 18:13:40 volumio sudo[4453]: pam_unix(sudo:session): session closed for user root May 07 18:13:40 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:40 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:13:40 volumio volumio[757]: info: Received Get System Info May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:13:40 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:40 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:40 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:40 volumio volumio[757]: info: Listing playlists May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:13:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:13:42 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 07 18:13:42 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:13:42 volumio volumio[757]: info: Received Get System Info May 07 18:13:42 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:13:42 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:13:42 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:13:42 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:42 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:42 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:13:43 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:13:43 volumio volumio[757]: info: Received Get System Info May 07 18:13:43 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:13:43 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:13:43 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:13:43 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:43 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:43 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:13:47 volumio systemd[1]: systemd-timedated.service: Succeeded. May 07 18:13:47 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse May 07 18:13:48 volumio volumio[757]: info: CoreCommandRouter::volumioGetQueue May 07 18:13:48 volumio volumio[757]: info: CoreStateMachine::getQueue May 07 18:13:48 volumio volumio[757]: info: CorePlayQueue::getQueue May 07 18:13:49 volumio ntpd[627]: Soliciting pool server 155.232.19.7 May 07 18:13:50 volumio ntpd[627]: Soliciting pool server 102.64.113.152 May 07 18:13:50 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 18:13:56 volumio ntpd[627]: Soliciting pool server 196.192.32.7 May 07 18:13:57 volumio volumio[757]: info: CoreCommandRouter::Close All Modals sent May 07 18:13:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:13:59 volumio volumio[757]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 18:13:59 volumio volumio[757]: info: Received Get System Version May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:13:59 volumio volumio[757]: info: Received Get System Info May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:13:59 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:59 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:13:59 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:13:59 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:13:59 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:14:10 volumio ntpd[627]: Soliciting pool server 196.44.136.165 May 07 18:14:10 volumio ntpd[627]: Soliciting pool server 196.44.136.162 May 07 18:14:16 volumio volumio[757]: error: error May 07 18:14:17 volumio volumio[757]: error: error May 07 18:14:31 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 07 18:14:58 volumio ntpd[627]: Soliciting pool server 102.64.112.164 May 07 18:14:58 volumio ntpd[627]: Soliciting pool server 176.97.192.150 May 07 18:14:58 volumio ntpd[627]: Soliciting pool server 196.10.54.58 May 07 18:15:03 volumio ntpd[627]: Soliciting pool server 41.60.207.40 May 07 18:15:07 volumio volumio[757]: error: error May 07 18:15:08 volumio ntpd[627]: Soliciting pool server 196.49.100.57 May 07 18:15:09 volumio ntpd[627]: Soliciting pool server 160.119.216.206 May 07 18:15:54 volumio volumio[757]: error: error May 07 18:16:02 volumio ntpd[627]: Soliciting pool server 160.119.216.202 May 07 18:16:05 volumio ntpd[627]: Soliciting pool server 102.216.37.99 May 07 18:16:12 volumio ntpd[627]: Soliciting pool server 41.220.128.73 May 07 18:16:14 volumio ntpd[627]: Soliciting pool server 102.64.113.151 May 07 18:17:06 volumio ntpd[627]: Soliciting pool server 102.130.49.195 May 07 18:17:13 volumio ntpd[627]: Soliciting pool server 41.175.51.165 May 07 18:17:14 volumio ntpd[627]: Soliciting pool server 2001:43f8:1f5:100::58 May 07 18:17:18 volumio ntpd[627]: Soliciting pool server 102.64.112.165 May 07 18:17:20 volumio ntpd[627]: Soliciting pool server 41.205.142.133 May 07 18:17:21 volumio ntpd[627]: Soliciting pool server 196.44.136.165 May 07 18:17:37 volumio volumio[757]: error: error May 07 18:18:11 volumio ntpd[627]: Soliciting pool server 196.10.52.57 May 07 18:18:21 volumio ntpd[627]: Soliciting pool server 2001:43f8:d60:300::206 May 07 18:18:27 volumio ntpd[627]: Soliciting pool server 41.205.143.146 May 07 18:18:28 volumio volumio[757]: error: error May 07 18:18:28 volumio ntpd[627]: Soliciting pool server 196.192.32.7 May 07 18:18:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:18:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:18:54 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:18:54 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:18:54 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:18:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:18:54 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101:3000 from 192.168.3.142 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 07 18:18:54 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:18:54 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:18:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 07 18:18:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 07 18:18:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:18:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:18:55 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:18:55 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:18:55 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:18:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:18:59 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:18:59 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:00 volumio sudo[5424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:19:00 volumio sudo[5424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:19:00 volumio sudo[5426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:19:00 volumio sudo[5426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:19:00 volumio sudo[5426]: pam_unix(sudo:session): session closed for user root May 07 18:19:00 volumio sudo[5424]: pam_unix(sudo:session): session closed for user root May 07 18:19:00 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:19:00 volumio sudo[5432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:19:00 volumio sudo[5432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:19:00 volumio sudo[5434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:19:00 volumio sudo[5434]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:19:00 volumio sudo[5434]: pam_unix(sudo:session): session closed for user root May 07 18:19:00 volumio sudo[5432]: pam_unix(sudo:session): session closed for user root May 07 18:19:00 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:00 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:19:00 volumio volumio[757]: info: Received Get System Info May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:19:00 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:00 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:00 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:00 volumio volumio[757]: info: Listing playlists May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 18:19:00 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:19:01 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:19:02 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 07 18:19:02 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:19:02 volumio volumio[757]: info: Received Get System Info May 07 18:19:02 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:19:02 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:19:02 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:19:02 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:02 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:02 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:19:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:19:03 volumio volumio[757]: info: Received Get System Info May 07 18:19:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:19:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:19:03 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:19:03 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:03 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:19:08 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:19:08 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:19:09 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:19:09 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:19:09 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 07 18:19:09 volumio volumio[757]: info: Received Get System Version May 07 18:19:09 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:19:10 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 18:19:14 volumio volumio[757]: error: error May 07 18:19:17 volumio ntpd[627]: Soliciting pool server 196.10.52.58 May 07 18:19:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:19:19 volumio volumio[757]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 18:19:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 18:19:19 volumio volumio[757]: info: Received Get System Version May 07 18:19:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:19:19 volumio volumio[757]: info: Received Get System Info May 07 18:19:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:19:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:19:19 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:19:19 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:19:24 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:19:24 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:19:24 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:19:24 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:24 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:24 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:19:28 volumio ntpd[627]: Soliciting pool server 2001:43f8:1f3:100::57 May 07 18:19:35 volumio ntpd[627]: Soliciting pool server 196.10.55.57 May 07 18:19:38 volumio ntpd[627]: Soliciting pool server 196.10.52.58 May 07 18:19:40 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:19:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:19:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:19:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:19:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 07 18:19:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:19:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:19:54 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:19:54 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:19:54 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:19:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:01 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:20:01 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:20:01 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:20:01 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:20:01 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 07 18:20:02 volumio volumio[757]: info: Received Get System Version May 07 18:20:02 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:20:04 volumio volumio[757]: error: error May 07 18:20:14 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:14 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:15 volumio sudo[5728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:20:15 volumio sudo[5728]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:20:15 volumio sudo[5730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:20:15 volumio sudo[5730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:20:15 volumio sudo[5730]: pam_unix(sudo:session): session closed for user root May 07 18:20:15 volumio sudo[5728]: pam_unix(sudo:session): session closed for user root May 07 18:20:15 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 07 18:20:15 volumio sudo[5736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:20:15 volumio sudo[5736]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:20:15 volumio sudo[5738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:20:15 volumio sudo[5738]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:20:15 volumio sudo[5736]: pam_unix(sudo:session): session closed for user root May 07 18:20:15 volumio sudo[5738]: pam_unix(sudo:session): session closed for user root May 07 18:20:15 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:15 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:20:15 volumio volumio[757]: info: Received Get System Info May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:20:15 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:15 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:15 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:15 volumio volumio[757]: info: Listing playlists May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:20:15 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:20:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 07 18:20:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:20:17 volumio volumio[757]: info: Received Get System Info May 07 18:20:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:20:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:20:17 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:20:17 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:17 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:20:18 volumio volumio[757]: info: Received Get System Info May 07 18:20:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:20:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:20:18 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:20:18 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:18 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:20:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:20:22 volumio ntpd[627]: Soliciting pool server 41.220.128.73 May 07 18:20:24 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:20:24 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:20:24 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:20:24 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:24 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:24 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:25 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 18:20:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:20:33 volumio volumio[757]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 18:20:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 18:20:33 volumio volumio[757]: info: Received Get System Version May 07 18:20:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:20:33 volumio volumio[757]: info: Received Get System Info May 07 18:20:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:20:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:20:33 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:20:33 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:33 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:37 volumio ntpd[627]: Soliciting pool server 2001:4200:7000::6 May 07 18:20:42 volumio ntpd[627]: Soliciting pool server 155.232.19.6 May 07 18:20:43 volumio ntpd[627]: Soliciting pool server 41.205.143.146 May 07 18:20:49 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:49 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:20:49 volumio volumio[757]: info: Received Get System Info May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:20:49 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:20:50 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:20:50 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:50 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:50 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:50 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:50 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:50 volumio volumio[757]: info: Listing playlists May 07 18:20:50 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:20:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:20:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:20:54 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:20:54 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:20:54 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:20:54 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:20:54 volumio volumio[757]: error: error May 07 18:21:17 volumio volumio[757]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 07 18:21:20 volumio volumio[757]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 07 18:21:20 volumio volumio[757]: info: Folder /tmp/plugins removed May 07 18:21:23 volumio volumio[757]: info: Check plugin dependencies May 07 18:21:23 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:21:23 volumio volumio[757]: info: Checking if plugin already exists May 07 18:21:23 volumio volumio[757]: info: Rename folder May 07 18:21:23 volumio volumio[757]: info: Folder /tmp/downloaded_plugin.zip removed May 07 18:21:23 volumio volumio[757]: info: Move to category May 07 18:21:28 volumio ntpd[627]: Soliciting pool server 102.216.37.100 May 07 18:21:50 volumio ntpd[627]: receive: Unexpected origin timestamp 0xebc5f906.1a3cf0f5 does not match aorg 0000000000.00000000 from server@41.60.207.40 xmt 0xebc5f90e.88ac55ea May 07 18:21:53 volumio ntpd[627]: Soliciting pool server 160.119.216.206 May 07 18:21:53 volumio ntpd[627]: Soliciting pool server 102.64.112.165 May 07 18:21:55 volumio volumio[757]: info: Checking if install.sh is present May 07 18:21:55 volumio volumio[757]: info: Executing install.sh May 07 18:21:55 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101:3000 from 192.168.3.142 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 May 07 18:21:55 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 07 18:21:55 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 07 18:21:55 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:21:55 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:21:55 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:21:55 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:21:55 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:21:55 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:21:55 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:21:55 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:21:56 volumio ntpd[627]: Soliciting pool server 102.64.112.165 May 07 18:21:56 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 07 18:21:56 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:21:56 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:21:56 volumio volumio[757]: error: error May 07 18:21:57 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 07 18:21:57 volumio ntpd[627]: Soliciting pool server 102.130.49.189 May 07 18:21:57 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 07 18:21:57 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 07 18:21:57 volumio sudo[6077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh May 07 18:21:57 volumio sudo[6077]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:21:57 volumio volumio[757]: info: Installing Go-librespot May 07 18:21:57 volumio volumio[757]: info: Checking old vollibrespot installs May 07 18:21:57 volumio systemd[1]: Reloading. May 07 18:21:58 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 07 18:21:58 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 07 18:21:58 volumio volumio[757]: info: Downloading daemon May 07 18:22:02 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:22:03 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:22:03 volumio volumio[757]: info: Received Get System Info May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:22:03 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:22:03 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:22:03 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:22:03 volumio volumio[757]: info: Listing playlists May 07 18:22:03 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:22:03 volumio ntpd[627]: Soliciting pool server 102.216.37.99 May 07 18:22:04 volumio ntpd[627]: Soliciting pool server 102.216.37.100 May 07 18:22:15 volumio volumio[757]: info: Creating directories May 07 18:22:15 volumio volumio[757]: info: Creating data directory May 07 18:22:16 volumio volumio[757]: info: Creating Start Script May 07 18:22:16 volumio systemd[1]: Reloading. May 07 18:22:16 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 07 18:22:16 volumio sudo[6077]: pam_unix(sudo:session): session closed for user root May 07 18:22:16 volumio volumio[757]: Plugin install end detected on script May 07 18:22:16 volumio volumio[757]: info: Install script completed May 07 18:22:16 volumio volumio[757]: info: Adding reference to registry May 07 18:22:16 volumio volumio[757]: info: Done installing plugin. May 07 18:22:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:22:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:22:16 volumio volumio[757]: info: Folder /tmp/plugins removed May 07 18:22:16 volumio volumio[757]: info: Folder /tmp/downloaded_plugin.zip removed May 07 18:22:16 volumio volumio[757]: info: Folder /data/temp removed May 07 18:22:21 volumio volumio[757]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytcr/volumio/buster/armhf May 07 18:22:28 volumio volumio[757]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytcr/volumio/buster/armhf May 07 18:22:28 volumio volumio[757]: info: Folder /tmp/plugins removed May 07 18:22:43 volumio ntpd[627]: Soliciting pool server 196.10.54.57 May 07 18:22:46 volumio volumio[757]: error: error May 07 18:22:57 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.142 UA: Mozilla/5.0 (Linux; Android 15; SM-F956B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:22:57 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:22:57 volumio volumio[757]: info: Received Get System Info May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:22:57 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:22:57 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:22:57 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:23:02 volumio ntpd[627]: Soliciting pool server 102.64.113.152 May 07 18:23:05 volumio ntpd[627]: Soliciting pool server 196.44.136.162 May 07 18:23:14 volumio volumio-remote-updater[2861]: [2025-05-07 15:23:14] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 07 18:23:16 volumio ntpd[627]: Soliciting pool server 102.216.37.100 May 07 18:23:19 volumio volumio-remote-updater[2861]: [2025-05-07 15:23:19] [connect] Successful connection May 07 18:23:32 volumio volumio-remote-updater[2861]: [2025-05-07 15:23:32] [connect] Successful connection May 07 18:23:33 volumio nmbd[601]: [2025/05/07 18:23:33.161327, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 07 18:23:33 volumio nmbd[601]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.101 for name WORKGROUP<1d>. May 07 18:23:33 volumio nmbd[601]: This response was from IP 192.168.3.1, reporting an IP address of 192.168.3.1. May 07 18:23:33 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:23:33 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:23:47 volumio volumio-remote-updater[2861]: [2025-05-07 15:23:47] [connect] Successful connection May 07 18:23:47 volumio volumio[757]: info: Listing playlists May 07 18:23:47 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:23:47 volumio volumio-remote-updater[2861]: [2025-05-07 15:23:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746631427 101 May 07 18:23:47 volumio volumio[757]: 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: 1 May 07 18:23:48 volumio ntpd[627]: Soliciting pool server 196.10.55.57 May 07 18:23:48 volumio volumio[757]: error: error May 07 18:23:49 volumio volumio[757]: info: Check plugin dependencies May 07 18:23:50 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:23:50 volumio volumio[757]: info: Checking if plugin already exists May 07 18:23:50 volumio volumio[757]: info: Rename folder May 07 18:23:51 volumio volumio[757]: info: Move to category May 07 18:23:51 volumio volumio[757]: info: Folder /tmp/downloaded_plugin.zip removed May 07 18:23:51 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 07 18:23:51 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 07 18:24:08 volumio ntpd[627]: 155.232.19.7 local addr 192.168.3.101 -> May 07 18:24:08 volumio ntpd[627]: Soliciting pool server 41.205.143.146 May 07 18:24:09 volumio ntpd[627]: Soliciting pool server 2001:4200:7000::6 May 07 18:24:13 volumio ntpd[627]: Soliciting pool server 176.97.192.150 May 07 18:24:17 volumio volumio-remote-updater[2861]: [2025-05-07 15:24:17] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 07 18:24:22 volumio volumio-remote-updater[2861]: [2025-05-07 15:24:22] [connect] Successful connection May 07 18:24:35 volumio volumio-remote-updater[2861]: [2025-05-07 15:24:35] [connect] Successful connection May 07 18:24:41 volumio volumio[757]: info: Checking if install.sh is present May 07 18:24:41 volumio volumio[757]: info: Executing install.sh May 07 18:24:42 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 07 18:24:42 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:24:42 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:24:42 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 07 18:24:42 volumio volumio[757]: error: error May 07 18:24:43 volumio sudo[6515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/ytcr/install.sh May 07 18:24:43 volumio sudo[6515]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:24:43 volumio sudo[6515]: pam_unix(sudo:session): session closed for user root May 07 18:24:43 volumio volumio[757]: info: YouTube Cast Receiver installed May 07 18:24:43 volumio volumio[757]: Plugin install end detected on script May 07 18:24:43 volumio volumio[757]: info: Install script completed May 07 18:24:43 volumio volumio[757]: info: Adding reference to registry May 07 18:24:43 volumio volumio[757]: info: Done installing plugin. May 07 18:24:43 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:24:43 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:24:43 volumio volumio[757]: info: Folder /tmp/plugins removed May 07 18:24:43 volumio volumio[757]: info: Folder /tmp/downloaded_plugin.zip removed May 07 18:24:43 volumio volumio[757]: info: Folder /data/temp removed May 07 18:24:50 volumio volumio-remote-updater[2861]: [2025-05-07 15:24:50] [connect] Successful connection May 07 18:24:50 volumio volumio-remote-updater[2861]: [2025-05-07 15:24:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746631490 101 May 07 18:24:50 volumio volumio[757]: 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: 3 May 07 18:24:53 volumio ntpd[627]: Soliciting pool server 196.10.54.58 May 07 18:25:14 volumio ntpd[627]: 41.60.207.40 local addr 192.168.3.101 -> May 07 18:25:17 volumio ntpd[627]: Soliciting pool server 2001:43f8:3e1:182::182 May 07 18:25:30 volumio ntpd[627]: Soliciting pool server 160.119.216.197 May 07 18:25:31 volumio ntpd[627]: Soliciting pool server 102.64.113.151 May 07 18:25:34 volumio volumio[757]: error: error May 07 18:26:00 volumio ntpd[627]: Soliciting pool server 102.130.49.189 May 07 18:26:15 volumio sudo[6851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:26:15 volumio sudo[6851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:26:15 volumio sudo[6854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:26:15 volumio sudo[6854]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:26:15 volumio sudo[6854]: pam_unix(sudo:session): session closed for user root May 07 18:26:16 volumio sudo[6851]: pam_unix(sudo:session): session closed for user root May 07 18:26:16 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 07 18:26:16 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:26:17 volumio sudo[6865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 18:26:17 volumio sudo[6865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:26:17 volumio sudo[6867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 18:26:17 volumio sudo[6867]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:26:17 volumio sudo[6865]: pam_unix(sudo:session): session closed for user root May 07 18:26:17 volumio sudo[6867]: pam_unix(sudo:session): session closed for user root May 07 18:26:17 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:26:17 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:26:17 volumio volumio[757]: info: Received Get System Info May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:26:17 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:26:17 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:26:17 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:26:17 volumio volumio[757]: info: Listing playlists May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:26:17 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:26:18 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:26:18 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:26:18 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 07 18:26:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:26:19 volumio volumio[757]: info: Received Get System Info May 07 18:26:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:26:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:26:19 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:26:19 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:26:19 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:26:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:26:19 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:26:23 volumio ntpd[627]: Soliciting pool server 2001:678:8::123 May 07 18:26:23 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:26:23 volumio volumio[757]: info: Received Get System Info May 07 18:26:23 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:26:23 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:26:23 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:26:23 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:26:23 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:26:23 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:26:24 volumio volumio[757]: error: error May 07 18:26:27 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 18:26:32 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:26:32 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:26:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:26:35 volumio volumio[757]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 18:26:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 18:26:35 volumio volumio[757]: info: Received Get System Version May 07 18:26:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:26:35 volumio volumio[757]: info: Received Get System Info May 07 18:26:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:26:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:26:35 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:26:35 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:26:35 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:26:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:26:43 volumio volumio[757]: info: Enabling plugin spop May 07 18:26:43 volumio volumio[757]: info: Loading plugin "spop"... May 07 18:26:47 volumio volumio[757]: info: PLUGIN START: spop May 07 18:26:47 volumio volumio[757]: info: Creating Spotify config file May 07 18:26:47 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:26:47 volumio volumio[757]: info: Done. May 07 18:26:47 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:26:47 volumio volumio[757]: info: Spotify config file written May 07 18:26:47 volumio volumio[757]: info: No need to fix Spotify hosts May 07 18:26:47 volumio sudo[6941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 07 18:26:47 volumio sudo[6941]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:26:50 volumio systemd[1]: Started go-librespot Daemon. May 07 18:26:50 volumio sudo[6941]: pam_unix(sudo:session): session closed for user root May 07 18:26:53 volumio volumio[757]: info: go-librespot daemon successfully initialized May 07 18:26:56 volumio go-librespot[6957]: go-librespot daemon starting... May 07 18:26:56 volumio volumio[757]: info: Initializing connection to go-librespot Websocket May 07 18:26:56 volumio volumio[757]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 07 18:26:58 volumio go-librespot[6957]: time="2025-05-07T18:26:58+03:00" level=info msg="running go-librespot 0.2.0" May 07 18:26:58 volumio go-librespot[6957]: time="2025-05-07T18:26:58+03:00" level=debug msg="no app state found" May 07 18:26:58 volumio go-librespot[6957]: time="2025-05-07T18:26:58+03:00" level=debug msg="stored credentials not found" May 07 18:26:58 volumio go-librespot[6957]: time="2025-05-07T18:26:58+03:00" level=info msg="generated new device id: 8e9b3cdd0dedfcb8a02fed4cf062b9b7b3140a94" May 07 18:26:58 volumio go-librespot[6957]: time="2025-05-07T18:26:58+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 07 18:26:59 volumio go-librespot[6957]: time="2025-05-07T18:26:59+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 07 18:26:59 volumio go-librespot[6957]: time="2025-05-07T18:26:59+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 07 18:26:59 volumio go-librespot[6957]: time="2025-05-07T18:26:59+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 07 18:26:59 volumio go-librespot[6957]: time="2025-05-07T18:26:59+03:00" level=info msg="zeroconf server listening on port 38835" May 07 18:26:59 volumio volumio[757]: info: Initializing connection to go-librespot Websocket May 07 18:26:59 volumio volumio[757]: info: Connection to go-librespot Websocket established May 07 18:27:00 volumio go-librespot[6957]: time="2025-05-07T18:27:00+03:00" level=debug msg="new websocket client" May 07 18:27:03 volumio volumio[757]: info: Getting Spotify volume May 07 18:27:03 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 07 18:27:03 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:03 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:03 volumio volumio[757]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 07 18:27:03 volumio volumio[757]: SPOTIFY: SPOTIFY VOLUME undefined May 07 18:27:03 volumio volumio[757]: SPOTIFY: VOLUMIO VOLUME 86 May 07 18:27:03 volumio volumio[757]: info: Aligning Spotify Volume to Volumio Volume May 07 18:27:03 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:03 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:03 volumio volumio[757]: info: Setting Spotify Volume from Volumio: 86 May 07 18:27:04 volumio volumio[757]: SPOTIFY: SETTING SPOTIFY VOLUME 86 May 07 18:27:04 volumio volumio[757]: info: Sending Spotify command with payload to local API: /player/volume May 07 18:27:12 volumio volumio[757]: error: error May 07 18:27:27 volumio ntpd[627]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 18:27:31 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:27:31 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:27:32 volumio ntpd[627]: 41.205.142.133 local addr 192.168.3.101 -> May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:33 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:27:33 volumio volumio[757]: info: Received Get System Info May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:27:33 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:33 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:33 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:33 volumio volumio[757]: info: Listing playlists May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:27:33 volumio volumio[757]: info: Received Get System Info May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:27:33 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:33 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:27:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:27:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:27:34 volumio volumio[757]: info: Received Get System Info May 07 18:27:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:27:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:27:34 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:27:34 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:34 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:27:36 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 07 18:27:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:27:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:27:41 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 18:27:44 volumio volumio[757]: info: Enabling plugin ytcr May 07 18:27:44 volumio volumio[757]: info: Loading plugin "ytcr"... May 07 18:27:50 volumio volumio-remote-updater[2861]: [2025-05-07 15:27:50] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 07 18:27:51 volumio volumio[757]: info: PLUGIN START: ytcr May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 18:27:51 volumio volumio[757]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 18:27:51 volumio volumio[757]: info: Received Get System Version May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:27:51 volumio volumio[757]: info: Received Get System Info May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:27:51 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:51 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:51 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:27:53 volumio volumio[757]: info: [yt-cast-receiver] DIAL server listening on port 8098 May 07 18:27:53 volumio volumio[757]: info: CoreCommandRouter::volumioRetrievevolume May 07 18:27:54 volumio volumio[757]: info: VolumeController:: Volume=86 Mute =false May 07 18:27:54 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:27:54 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:54 volumio volumio[757]: info: CoreStateMachine::pushState May 07 18:27:54 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:27:54 volumio volumio[757]: info: CoreCommandRouter::volumioPushState May 07 18:27:54 volumio volumio[757]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 07 18:27:54 volumio volumio[757]: info: Done. May 07 18:27:55 volumio volumio-remote-updater[2861]: [2025-05-07 15:27:55] [connect] Successful connection May 07 18:27:55 volumio volumio-remote-updater[2861]: [2025-05-07 15:27:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746631675 101 May 07 18:27:55 volumio volumio[757]: 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: 5 May 07 18:28:02 volumio volumio[757]: error: error May 07 18:28:09 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:28:09 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:28:11 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:28:11 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:28:32 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:28:32 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:28:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 18:28:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 07 18:28:33 volumio volumio[757]: info: Received Get System Version May 07 18:28:33 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:28:39 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:28:39 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:28:42 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 18:28:42 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 18:28:53 volumio volumio[757]: error: error May 07 18:29:42 volumio volumio[757]: error: error May 07 18:30:07 volumio volumio[757]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 07 18:30:11 volumio volumio[757]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 07 18:30:11 volumio volumio[757]: info: Folder /tmp/plugins removed May 07 18:30:12 volumio volumio[757]: info: Check plugin dependencies May 07 18:30:12 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 18:30:12 volumio volumio[757]: info: Checking if plugin already exists May 07 18:30:12 volumio volumio[757]: info: Rename folder May 07 18:30:12 volumio volumio[757]: info: Folder /tmp/downloaded_plugin.zip removed May 07 18:30:12 volumio volumio[757]: info: Move to category May 07 18:30:30 volumio volumio-remote-updater[2861]: [2025-05-07 15:30:30] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 07 18:30:35 volumio volumio-remote-updater[2861]: [2025-05-07 15:30:35] [connect] Successful connection May 07 18:30:36 volumio volumio[757]: info: Checking if install.sh is present May 07 18:30:36 volumio volumio[757]: info: Executing install.sh May 07 18:30:36 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 07 18:30:36 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:30:36 volumio volumio-remote-updater[2861]: [2025-05-07 15:30:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746631835 101 May 07 18:30:36 volumio volumio[757]: 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: 6 May 07 18:30:36 volumio sudo[7783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 07 18:30:36 volumio sudo[7783]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:30:37 volumio volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 07 18:30:37 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:30:37 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:30:37 volumio volumio[757]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 07 18:30:37 volumio volumio[757]: error: error May 07 18:30:38 volumio volumio[757]: info: creating filters folder and copying demo filters May 07 18:30:40 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:30:40 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:30:40 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:30:40 volumio volumio[757]: info: Received Get System Info May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:30:40 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:30:40 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:30:40 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:30:40 volumio volumio[757]: info: Listing playlists May 07 18:30:40 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:30:40 volumio volumio[757]: info: copying demo flters May 07 18:30:41 volumio volumio[757]: info: ./presets/ May 07 18:30:41 volumio volumio[757]: info: ./presets/2XEQ15/ May 07 18:30:41 volumio volumio[757]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 07 18:30:41 volumio sudo[7808]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 07 18:30:41 volumio sudo[7808]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:30:41 volumio volumio[757]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/PEQ/ May 07 18:30:41 volumio volumio[757]: info: ./presets/convfir/ May 07 18:30:41 volumio volumio[757]: info: ./presets/convfir/kef.json May 07 18:30:41 volumio volumio[757]: info: ./presets/convfir/test.json May 07 18:30:41 volumio volumio[757]: info: ./presets/EQ15/ May 07 18:30:41 volumio volumio[757]: info: ./presets/EQ15/.Bass-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/EQ15/.Classic-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/EQ15/.Voice-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: ./presets/EQ15/.Rock-FusionDsp.json May 07 18:30:41 volumio volumio[757]: info: Installing/fusiondsp dependencies May 07 18:30:42 volumio volumio[757]: info: Get:1 https://deb.nodesource.com/node_14.x buster InRelease [4584 B] May 07 18:30:42 volumio volumio[757]: info: Get:2 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] May 07 18:30:42 volumio volumio[757]: info: Get:3 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] May 07 18:30:48 volumio volumio[757]: info: Get:4 https://deb.nodesource.com/node_14.x buster/main armhf Packages [786 B] May 07 18:30:48 volumio volumio[757]: info: Get:5 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] May 07 18:30:50 volumio volumio[757]: info: Get:6 http://raspbian.raspberrypi.org/raspbian buster/main armhf Packages [13.0 MB] May 07 18:31:01 volumio volumio[757]: info: Get:7 http://raspbian.raspberrypi.org/raspbian buster/contrib armhf Packages [58.8 kB] May 07 18:31:02 volumio volumio[757]: info: Get:8 http://raspbian.raspberrypi.org/raspbian buster/non-free armhf Packages [110 kB] May 07 18:31:02 volumio volumio[757]: info: Get:9 http://raspbian.raspberrypi.org/raspbian buster/rpi armhf Packages [1360 B] May 07 18:31:04 volumio volumio[757]: info: Enabling plugin fusiondsp May 07 18:31:04 volumio volumio[757]: info: Loading plugin "fusiondsp"... May 07 18:31:08 volumio volumio[757]: info: Preparing to generate the ALSA configuration file May 07 18:31:31 volumio volumio-remote-updater[2861]: [2025-05-07 15:31:31] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 07 18:31:32 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:31:34 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 07 18:31:34 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 07 18:31:34 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 07 18:31:34 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 07 18:31:34 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 07 18:31:34 volumio volumio[757]: info: Asound.conf file unchanged, so no further update is needed May 07 18:31:34 volumio volumio[757]: info: Output device has changed, restarting MPD May 07 18:31:34 volumio sudo[8203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 18:31:34 volumio sudo[8203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:31:34 volumio volumio[757]: info: Output device has changed, restarting Shairport Sync May 07 18:31:34 volumio sudo[8203]: pam_unix(sudo:session): session closed for user root May 07 18:31:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:31:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 18:31:34 volumio sudo[8206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 18:31:34 volumio sudo[8206]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:31:34 volumio systemd[1]: Stopping Music Player Daemon... May 07 18:31:34 volumio volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 18:31:34 volumio volumio[757]: info: PLUGIN START: fusiondsp May 07 18:31:34 volumio volumio[757]: info: Loading i18n strings for locale en May 07 18:31:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 07 18:31:34 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:31:34 volumio volumio[757]: info: FusionDsp - mixtype--------------------- Hardware May 07 18:31:34 volumio volumio[757]: info: Preparing to generate the ALSA configuration file May 07 18:31:34 volumio volumio[757]: info: Done. May 07 18:31:34 volumio volumio[757]: info: FusionDsp - May 07 18:31:35 volumio volumio[757]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 07 18:31:35 volumio volumio[757]: info: Reading ALSA contributions from plugins. May 07 18:31:35 volumio volumio[757]: info: FusionDsp - undefined May 07 18:31:35 volumio volumio[757]: verbose: New Socket.io Connection to 192.168.3.101 from 192.168.3.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 07 18:31:35 volumio volumio[757]: info: MPD Permissions set May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:31:35 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::volumioGetVisibleSources May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:31:35 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:31:35 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 18:31:35 volumio volumio[757]: info: Received Get System Info May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 18:31:35 volumio volumio[757]: info: Discovery: Getting this device information May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:31:35 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::volumioGetState May 07 18:31:35 volumio volumio[757]: info: CorePlayQueue::getTrack 0 May 07 18:31:35 volumio volumio[757]: info: Listing playlists May 07 18:31:35 volumio volumio[757]: error: error May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:31:35 volumio volumio[757]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 18:31:35 volumio volumio[757]: info: FusionDsp - May 07 18:31:35 volumio volumio[757]: info: FusionDsp - May 07 18:31:36 volumio volumio-remote-updater[2861]: [2025-05-07 15:31:36] [connect] Successful connection May 07 18:31:36 volumio volumio[757]: info: FusionDsp - undefined May 07 18:31:37 volumio volumio[757]: info: FusionDsp - undefined May 07 18:31:37 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 18:31:48 volumio volumio[757]: info: camilladsp service started and running in background, instance 1 May 07 18:31:48 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 18:31:48 volumio volumio[757]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 07 18:31:48 volumio volumio[757]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 07 18:31:48 volumio volumio[757]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 07 18:31:48 volumio volumio[757]: info: FusionDsp loaded May 07 18:31:48 volumio volumio[757]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 18:31:48 volumio volumio[757]: info: FusionDsp - Reporting Fusion DSP Enabled May 07 18:31:48 volumio volumio[757]: info: Adding Signal Path Element [object Object] May 07 18:31:48 volumio volumio[757]: info: Adding fusiondspeq DSP Signal Path Element May 07 18:31:48 volumio volumio[757]: info: FusionDsp - ---- installed callbackRead May 07 18:31:48 volumio volumio[757]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 18:31:48 volumio sudo[8258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 07 18:31:48 volumio sudo[8258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:31:48 volumio volumio[757]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 07 18:31:48 volumio volumio[757]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 07 18:31:48 volumio volumio[757]: at onErrorNT (internal/child_process.js:465:16) May 07 18:31:48 volumio volumio[757]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 07 18:31:48 volumio volumio[757]: at runNextTicks (internal/process/task_queues.js:62:3) May 07 18:31:48 volumio volumio[757]: at listOnTimeout (internal/timers.js:523:9) May 07 18:31:48 volumio volumio[757]: at processTimers (internal/timers.js:497:7) { May 07 18:31:48 volumio volumio[757]: errno: -2, May 07 18:31:48 volumio volumio[757]: code: 'ENOENT', May 07 18:31:48 volumio volumio[757]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 07 18:31:48 volumio volumio[757]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 07 18:31:48 volumio volumio[757]: spawnargs: [ May 07 18:31:48 volumio volumio[757]: '-p', May 07 18:31:48 volumio volumio[757]: 9876, May 07 18:31:48 volumio volumio[757]: '-o', May 07 18:31:48 volumio volumio[757]: '/tmp/camilladsp.log', May 07 18:31:48 volumio volumio[757]: '-l', May 07 18:31:48 volumio volumio[757]: 'warn', May 07 18:31:48 volumio volumio[757]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 07 18:31:48 volumio volumio[757]: ] May 07 18:31:48 volumio volumio[757]: } May 07 18:31:48 volumio volumio[757]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 18:31:48 volumio sudo[8258]: pam_unix(sudo:session): session closed for user root May 07 18:31:48 volumio systemd[1]: mpd.service: Succeeded. May 07 18:31:48 volumio systemd[1]: Stopped Music Player Daemon. May 07 18:31:48 volumio systemd[1]: Starting Music Player Daemon... May 07 18:31:48 volumio sudo[8268]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 07 18:31:48 volumio sudo[8268]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 18:31:48 volumio sudo[8268]: pam_unix(sudo:session): session closed for user root May 07 18:31:49 volumio volumio-remote-updater[2861]: [2025-05-07 15:31:49] [connect] Successful connection May 07 18:31:53 volumio sudo[8331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-07 17:30 May 07 18:31:53 volumio sudo[8331]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"