-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Sun 2025-09-14 07:47:11 IST. -- Sep 14 07:46:01 rme volumio[980]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 14 07:46:01 rme volumio[980]: verbose: New Socket.io Connection to 192.168.1.24:3000 from 192.168.1.30 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 14 07:46:06 rme volumio[980]: info: Adding plugin bluetooth to MyMusic Plugins Sep 14 07:46:06 rme volumio[980]: info: Adding plugin multiroom to MyMusic Plugins Sep 14 07:46:06 rme volumio[980]: info: Adding plugin metavolumio to MyMusic Plugins Sep 14 07:46:06 rme volumio[980]: info: Adding plugin cd_controller to MyMusic Plugins Sep 14 07:46:06 rme volumio[980]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 14 07:46:06 rme volumio[980]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 14 07:46:06 rme volumio[980]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 14 07:46:06 rme volumio[980]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 14 07:46:07 rme volumio[980]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 14 07:46:07 rme volumio[980]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 14 07:46:07 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:07 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:07 rme volumio[980]: info: Starting MyVolumio Remote Streaming Endpoints Sep 14 07:46:07 rme volumio[980]: info: MyVolumio login type: Token Sep 14 07:46:07 rme volumio[980]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 14 07:46:07 rme volumio[980]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 14 07:46:08 rme volumio[980]: info: Starting Streaming Service Transparent Proxy Sep 14 07:46:08 rme volumio[980]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 14 07:46:08 rme volumio[980]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 14 07:46:08 rme volumio[980]: info: Streaming services startup Sep 14 07:46:08 rme volumio[980]: info: Starting Streaming Daemon Sep 14 07:46:08 rme sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 07:46:08 rme sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:08 rme volumio[980]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 14 07:46:08 rme sudo[1204]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:08 rme volumio[980]: error: Cannot start Volumio Streaming Daemon Sep 14 07:46:08 rme volumio[980]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 07:46:08 rme volumio[980]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 07:46:08 rme volumio[980]: error: MyVolumio Custom Token format not valid, refreshing it Sep 14 07:46:08 rme volumio[980]: STREAMING PROXY: Starting server on port 3245 Sep 14 07:46:08 rme volumio[980]: Node JS runtime: 14 Sep 14 07:46:09 rme volumio[980]: info: MyVolumio login type: Token Sep 14 07:46:09 rme volumio[980]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 14 07:46:09 rme volumio[980]: info: MyVolumio token set successfully Sep 14 07:46:09 rme volumio[980]: info: MYVOLUMIO: Adding device Sep 14 07:46:09 rme volumio[980]: info: MYVOLUMIO: Evaluating Server Sep 14 07:46:10 rme volumio[980]: info: MyVolumio status changed Sep 14 07:46:10 rme volumio[980]: info: Streaming services startup Sep 14 07:46:10 rme volumio[980]: info: Starting Streaming Daemon Sep 14 07:46:10 rme volumio[980]: info: Removing browser output: myVolumio user plan is not superstar Sep 14 07:46:10 rme volumio[980]: info: Removing audio output: Sep 14 07:46:10 rme volumio[980]: info: Stoppping Tunnel 1 Sep 14 07:46:10 rme sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 14 07:46:10 rme sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:10 rme sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 14 07:46:10 rme sudo[1229]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:10 rme sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:10 rme volumio[980]: error: Cannot start Volumio Streaming Daemon Sep 14 07:46:10 rme volumio[980]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 14 07:46:10 rme volumio[980]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 14 07:46:10 rme sudo[1232]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:10 rme volumio[980]: info: Remote SSH Stopped Sep 14 07:46:10 rme volumio[980]: info: Setting Geolocation for MyVolumio to eu3 Sep 14 07:46:10 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:10 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:10 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:11 rme volumio[980]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 14 07:46:11 rme volumio[980]: info: Updating MyVolumio device info Sep 14 07:46:11 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:11 rme volumio[980]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 14 07:46:15 rme volumio[980]: info: MYVOLUMIO: Adding device Sep 14 07:46:15 rme volumio[980]: info: MYVOLUMIO: Evaluating Server Sep 14 07:46:15 rme volumio[980]: info: Setting Geolocation for MyVolumio to eu3 Sep 14 07:46:15 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:15 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:15 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:16 rme volumio[980]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 14 07:46:16 rme volumio[980]: info: Updating MyVolumio device info Sep 14 07:46:16 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:16 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:16 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:17 rme volumio[980]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 14 07:46:22 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 07:46:22 rme volumio[980]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 14 07:46:22 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:22 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:23 rme systemd[1]: systemd-timedated.service: Succeeded. Sep 14 07:46:23 rme sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 07:46:23 rme sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:23 rme sudo[1283]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:23 rme sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 07:46:23 rme sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:23 rme sudo[1285]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:23 rme volumio[980]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.26 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:23 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 07:46:23 rme volumio[980]: info: Received Get System Info Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 07:46:23 rme volumio[980]: info: Discovery: Getting this device information Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:23 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:23 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:23 rme volumio[980]: info: Listing playlists Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 14 07:46:23 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 07:46:24 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 07:46:25 rme volumio-remote-updater[576]: No test mode Sep 14 07:46:25 rme volumio-remote-updater[576]: No alpha test mode Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 14 07:46:25 rme volumio[980]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 07:46:25 rme volumio[980]: info: Received Get System Info Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 07:46:25 rme volumio[980]: info: Discovery: Getting this device information Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:25 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:25 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 07:46:26 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 07:46:26 rme volumio[980]: info: Received Get System Info Sep 14 07:46:26 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 07:46:26 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 07:46:26 rme volumio[980]: info: Discovery: Getting this device information Sep 14 07:46:26 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:26 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:26 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 14 07:46:27 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 07:46:28 rme volumio[980]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 14 07:46:28 rme volumio[980]: info: Completed starting MyVolumio Plugin Sep 14 07:46:28 rme volumio[980]: [Metrics] CommandRouter: 37s 900.59ms Sep 14 07:46:28 rme volumio[980]: info: CoreCommandRouter::volumiosetStartupVolume Sep 14 07:46:28 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:28 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:28 rme volumio[980]: info: CoreCommandRouter::Close All Modals sent Sep 14 07:46:28 rme volumio[980]: info: CoreCommandRouter::Close All Modals sent Sep 14 07:46:29 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 14 07:46:29 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 07:46:29 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 14 07:46:33 rme kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 3 Sep 14 07:46:33 rme kernel: xhci_hcd 0000:01:00.0: @0000000429516790 00000000 00000000 0e000000 02048000 Sep 14 07:46:33 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 07:46:35 rme ntpd[749]: Soliciting pool server 193.1.12.167 Sep 14 07:46:35 rme volumio[980]: info: BOOT COMPLETED Sep 14 07:46:36 rme ntpd[749]: Soliciting pool server 85.91.1.164 Sep 14 07:46:36 rme ntpd[749]: Soliciting pool server 31.57.56.239 Sep 14 07:46:36 rme ntpd[749]: Soliciting pool server 188.125.64.7 Sep 14 07:46:36 rme volumio[980]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Sep 14 07:46:36 rme sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 07:46:36 rme sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme sudo[1355]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:36 rme sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 07:46:36 rme sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:36 rme volumio[980]: info: MPD Permissions set Sep 14 07:46:36 rme systemd[1]: Stopping Music Player Daemon... Sep 14 07:46:36 rme systemd[1]: mpd.service: Succeeded. Sep 14 07:46:36 rme systemd[1]: Stopped Music Player Daemon. Sep 14 07:46:36 rme systemd[1]: Starting Music Player Daemon... Sep 14 07:46:36 rme sudo[1364]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 07:46:36 rme sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:36 rme sudo[1364]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:36 rme mpd[1366]: Sep 14 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 07:46:36 rme systemd[1]: Started Music Player Daemon. Sep 14 07:46:36 rme sudo[1357]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:37 rme volumio[980]: error: updateQueue error: null Sep 14 07:46:37 rme ntpd[749]: Soliciting pool server 162.159.200.1 Sep 14 07:46:37 rme ntpd[749]: Soliciting pool server 85.91.1.180 Sep 14 07:46:37 rme ntpd[749]: Soliciting pool server 193.1.8.106 Sep 14 07:46:37 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:37 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:37 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:37 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:38 rme ntpd[749]: Soliciting pool server 137.191.233.71 Sep 14 07:46:38 rme ntpd[749]: Soliciting pool server 162.159.200.123 Sep 14 07:46:39 rme ntpd[749]: Soliciting pool server 52.17.231.73 Sep 14 07:46:40 rme ntpd[749]: Soliciting pool server 2a00:1288:110:f600::1000 Sep 14 07:46:42 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 07:46:42 rme volumio[980]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 14 07:46:42 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 14 07:46:42 rme volumio[980]: info: Received Get System Version Sep 14 07:46:42 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 07:46:42 rme volumio[980]: info: Received Get System Info Sep 14 07:46:42 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 07:46:42 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 07:46:42 rme volumio[980]: info: Discovery: Getting this device information Sep 14 07:46:42 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:42 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:42 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 07:46:45 rme volumio[980]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::volumioGetState Sep 14 07:46:45 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:45 rme volumio[980]: info: Restoring Previous Volume level: 100 false false Sep 14 07:46:45 rme volumio[980]: info: Output device has changed, restarting MPD Sep 14 07:46:45 rme sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 07:46:45 rme volumio[980]: info: Volume configurations have been set Sep 14 07:46:45 rme sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:45 rme sudo[1384]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:45 rme sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 07:46:45 rme sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:45 rme systemd[1]: Stopping Music Player Daemon... Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 14 07:46:45 rme volumio[980]: info: Updating Volume Controller Parameters: Device: 5 Name: ADI-2 DAC (53980261) Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 14 07:46:45 rme systemd[1]: mpd.service: Succeeded. Sep 14 07:46:45 rme systemd[1]: Stopped Music Player Daemon. Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 14 07:46:45 rme volumio[980]: info: Disabling external Volume Control Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 07:46:45 rme volumio[980]: info: CoreStateMachine::pushState Sep 14 07:46:45 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::volumioPushState Sep 14 07:46:45 rme volumio[980]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Sep 14 07:46:45 rme volumio[980]: info: MPD Permissions set Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme systemd[1]: Starting Music Player Daemon... Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 07:46:45 rme sudo[1391]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 07:46:45 rme sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:45 rme sudo[1391]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 14 07:46:45 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 07:46:45 rme mpd[1401]: Sep 14 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 07:46:45 rme systemd[1]: Started Music Player Daemon. Sep 14 07:46:45 rme sudo[1386]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:45 rme volumio[980]: error: updateQueue error: null Sep 14 07:46:46 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:46 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 07:46:46 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 07:46:46 rme volumio[980]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"basic","playerName":"rme","dsdFormat":null,"card":"5","mixerType":"None","mixer":null} Sep 14 07:46:46 rme volumio[980]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Sep 14 07:46:46 rme sudo[1407]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty= ruser=volumio rhost= user=volumio Sep 14 07:46:48 rme sudo[1407]: pam_unix(sudo:auth): conversation failed Sep 14 07:46:48 rme sudo[1407]: pam_unix(sudo:auth): auth could not identify password for [volumio] Sep 14 07:46:48 rme sudo[1407]: volumio : 1 incorrect password attempt ; TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Sep 14 07:46:48 rme volumio[980]: error: [squeezelite_mc] Failed to execute cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf: [sudo] password for volumio: Sorry, try again. Sep 14 07:46:48 rme volumio[980]: [sudo] password for volumio: Sep 14 07:46:48 rme volumio[980]: sudo: no password was provided Sep 14 07:46:48 rme volumio[980]: sudo: 1 incorrect password attempt Sep 14 07:46:48 rme volumio[980]: Command failed: echo volumio | sudo -S cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Sep 14 07:46:48 rme volumio[980]: [sudo] password for volumio: Sorry, try again. Sep 14 07:46:48 rme volumio[980]: [sudo] password for volumio: Sep 14 07:46:48 rme volumio[980]: sudo: no password was provided Sep 14 07:46:48 rme volumio[980]: sudo: 1 incorrect password attempt Sep 14 07:46:48 rme volumio[980]: Error: Command failed: echo volumio | sudo -S cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Sep 14 07:46:48 rme volumio[980]: [sudo] password for volumio: Sorry, try again. Sep 14 07:46:48 rme volumio[980]: [sudo] password for volumio: Sep 14 07:46:48 rme volumio[980]: sudo: no password was provided Sep 14 07:46:48 rme volumio[980]: sudo: 1 incorrect password attempt Sep 14 07:46:48 rme volumio[980]: at ChildProcess.exithandler (child_process.js:308:12) Sep 14 07:46:48 rme volumio[980]: at ChildProcess.emit (events.js:315:20) Sep 14 07:46:48 rme volumio[980]: at maybeClose (internal/child_process.js:1048:16) Sep 14 07:46:48 rme volumio[980]: at Socket. (internal/child_process.js:439:11) Sep 14 07:46:48 rme volumio[980]: at Socket.emit (events.js:315:20) Sep 14 07:46:48 rme volumio[980]: at Pipe. (net.js:673:12) Sep 14 07:46:48 rme volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 07:46:48 rme volumio[980]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 14 07:46:48 rme sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 14 07:46:48 rme sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:46:48 rme sudo[1409]: pam_unix(sudo:session): session closed for user root Sep 14 07:46:49 rme volumio[980]: info: VolumeController::SetAlsaVolume100 Sep 14 07:46:49 rme volumio[980]: info: CoreStateMachine::pushState Sep 14 07:46:49 rme volumio[980]: info: CorePlayQueue::getTrack 0 Sep 14 07:46:49 rme volumio[980]: info: CoreCommandRouter::volumioPushState Sep 14 07:46:58 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 07:46:58 rme volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 14 07:47:01 rme volumio[980]: info: Starting Uninstall of plugin music_service - squeezelite_mc Sep 14 07:47:01 rme volumio[980]: info: Uninstalling plugin squeezelite_mc Sep 14 07:47:01 rme volumio[980]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Sep 14 07:47:01 rme volumio[980]: info: [squeezelite_mc] Proxy server stopped Sep 14 07:47:01 rme sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Sep 14 07:47:01 rme sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:47:01 rme sudo[1429]: pam_unix(sudo:session): session closed for user root Sep 14 07:47:02 rme volumio[980]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 14 07:47:02 rme sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 14 07:47:02 rme sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 07:47:02 rme sudo[1432]: pam_unix(sudo:session): session closed for user root Sep 14 07:47:02 rme volumio[980]: info: Disabling plugin squeezelite_mc Sep 14 07:47:02 rme volumio[980]: info: Checking if uninstall.sh is present Sep 14 07:47:02 rme volumio[980]: info: Executing uninstall.sh Sep 14 07:47:02 rme sudo[1436]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty= ruser=volumio rhost= user=volumio Sep 14 07:47:03 rme sudo[1436]: pam_unix(sudo:auth): conversation failed Sep 14 07:47:03 rme sudo[1436]: pam_unix(sudo:auth): auth could not identify password for [volumio] Sep 14 07:47:03 rme sudo[1436]: volumio : 1 incorrect password attempt ; TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/squeezelite_mc/uninstall.sh Sep 14 07:47:03 rme volumio[980]: info: Uninstall script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/music_service/squeezelite_mc/uninstall.sh > /tmp/installog Sep 14 07:47:03 rme volumio[980]: [sudo] password for volumio: Sorry, try again. Sep 14 07:47:03 rme volumio[980]: [sudo] password for volumio: Sep 14 07:47:03 rme volumio[980]: sudo: no password was provided Sep 14 07:47:03 rme volumio[980]: sudo: 1 incorrect password attempt Sep 14 07:47:11 rme volumio[980]: info: Starting Uninstall of plugin music_service - squeezelite_mc Sep 14 07:47:11 rme volumio[980]: info: Uninstalling plugin squeezelite_mc Sep 14 07:47:11 rme volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 07:47:11 rme volumio[980]: TypeError: Cannot read property 'coreCommand' of null Sep 14 07:47:11 rme volumio[980]: at SqueezeliteMCContext.toast (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:56:79) Sep 14 07:47:11 rme volumio[980]: at ControllerSqueezeliteMC.onStop (/data/plugins/music_service/squeezelite_mc/dist/index.js:244:40) Sep 14 07:47:11 rme volumio[980]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 14 07:47:11 rme volumio[980]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Sep 14 07:47:11 rme volumio[980]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Sep 14 07:47:11 rme volumio[980]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Sep 14 07:47:11 rme volumio[980]: at Socket.emit (events.js:315:20) Sep 14 07:47:11 rme volumio[980]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 14 07:47:11 rme volumio[980]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 14 07:47:11 rme volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 07:47:11 rme sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 07:46 Sep 14 07:47:11 rme sudo[1460]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"