Jun 07 15:20:05 test volumio[978]: error: MyVolumio Plugin failed to start in a timely fashion Jun 07 15:20:05 test volumio[978]: [Metrics] CommandRouter: 33s 184.46ms Jun 07 15:20:05 test volumio[978]: info: CoreCommandRouter::volumiosetStartupVolume Jun 07 15:20:05 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:05 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:05 test volumio[978]: info: CoreCommandRouter::Close All Modals sent Jun 07 15:20:05 test volumio[978]: info: CoreCommandRouter::Close All Modals sent Jun 07 15:20:06 test volumio[978]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jun 07 15:20:06 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jun 07 15:20:06 test volumio[978]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jun 07 15:20:06 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jun 07 15:20:06 test sudo[1332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 15:20:06 test sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:06 test sudo[1332]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:06 test sudo[1334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 15:20:06 test sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:06 test sudo[1336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 15:20:06 test sudo[1336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:06 test sudo[1339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 15:20:06 test sudo[1336]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:06 test sudo[1339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:07 test systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 07 15:20:07 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 07 15:20:07 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 15:20:07 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 07 15:20:07 test systemd[1]: mpd.service: Deactivated successfully. Jun 07 15:20:07 test systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 15:20:07 test systemd[1]: mpd.service: Consumed 5.732s CPU time. Jun 07 15:20:07 test systemd[1]: mpd.socket: Deactivated successfully. Jun 07 15:20:07 test systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 15:20:07 test systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 15:20:07 test systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 15:20:07 test systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 15:20:07 test sudo[1344]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 15:20:07 test sudo[1344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 15:20:07 test sudo[1344]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:08 test volumio[978]: info: MPD Permissions set Jun 07 15:20:08 test volumio[978]: info: MPD Permissions set Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:08 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 07 15:20:08 test volumio[978]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 07 15:20:08 test volumio[978]: info: Adding plugin bluetooth to MyMusic Plugins Jun 07 15:20:08 test volumio[978]: info: Adding plugin multiroom to MyMusic Plugins Jun 07 15:20:08 test volumio[978]: info: Adding plugin metavolumio to MyMusic Plugins Jun 07 15:20:08 test volumio[978]: info: Adding plugin cd_controller to MyMusic Plugins Jun 07 15:20:09 test volumio[978]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 07 15:20:09 test volumio[978]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 07 15:20:09 test volumio[978]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 07 15:20:09 test mpd[1346]: 2025-06-07T15:20:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 15:20:09 test systemd[1]: Started mpd.service - Music Player Daemon. Jun 07 15:20:09 test sudo[1339]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:09 test sudo[1334]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:10 test volumio[978]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 07 15:20:10 test volumio[978]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 07 15:20:10 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:10 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:10 test volumio[978]: info: Starting MyVolumio Remote Streaming Endpoints Jun 07 15:20:10 test volumio[978]: info: MyVolumio login type: Token Jun 07 15:20:10 test volumio[978]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 07 15:20:10 test volumio[978]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 07 15:20:11 test volumio[978]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 07 15:20:11 test volumio[978]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 07 15:20:11 test volumio[978]: info: Streaming services startup Jun 07 15:20:11 test volumio[978]: info: Starting Streaming Daemon Jun 07 15:20:11 test volumio[978]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 07 15:20:11 test sudo[1364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 15:20:11 test sudo[1364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:11 test sudo[1364]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:11 test volumio[978]: error: Cannot start Volumio Streaming Daemon Jun 07 15:20:11 test volumio[978]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 07 15:20:11 test volumio[978]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 07 15:20:11 test volumio[978]: error: MPD error: The expression evaluated to a falsy value: Jun 07 15:20:11 test volumio[978]: assert.ok(self.idling) Jun 07 15:20:11 test volumio[978]: error: The expression evaluated to a falsy value: Jun 07 15:20:11 test volumio[978]: assert.ok(self.idling) Jun 07 15:20:11 test volumio[978]: error: updateQueue error: null Jun 07 15:20:11 test volumio[978]: error: MyVolumio Custom Token format not valid, refreshing it Jun 07 15:20:12 test volumio[978]: info: BOOT COMPLETED Jun 07 15:20:12 test volumio[978]: info: MyVolumio login type: Token Jun 07 15:20:12 test systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 07 15:20:13 test volumio[978]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 07 15:20:13 test volumio[978]: info: MyVolumio token set successfully Jun 07 15:20:13 test volumio[978]: info: MYVOLUMIO: Adding device Jun 07 15:20:13 test volumio[978]: info: MYVOLUMIO: Evaluating Server Jun 07 15:20:13 test volumio[978]: info: MyVolumio status changed Jun 07 15:20:13 test volumio[978]: info: Streaming services startup Jun 07 15:20:13 test volumio[978]: info: Starting Streaming Daemon Jun 07 15:20:13 test volumio[978]: info: Removing browser output: myVolumio user plan is not superstar Jun 07 15:20:13 test volumio[978]: info: Removing audio output: Jun 07 15:20:13 test volumio[978]: info: Stoppping Tunnel 1 Jun 07 15:20:13 test sudo[1390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 07 15:20:13 test sudo[1390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:13 test sudo[1392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 07 15:20:13 test sudo[1392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:13 test sudo[1390]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:13 test volumio[978]: error: Cannot start Volumio Streaming Daemon Jun 07 15:20:13 test volumio[978]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 07 15:20:13 test volumio[978]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 07 15:20:13 test sudo[1392]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:13 test volumio[978]: info: Remote SSH Stopped Jun 07 15:20:14 test volumio[978]: info: Setting Geolocation for MyVolumio to eu8 Jun 07 15:20:14 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:14 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:14 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:14 test volumio[978]: info: Successfully Added MyVolumio device Jun 07 15:20:15 test volumio[978]: info: Updating MyVolumio device info Jun 07 15:20:15 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:15 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:15 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:15 test volumio[978]: info: Successfully Updated MyVolumio device Jun 07 15:20:22 test volumio[978]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::volumioGetState Jun 07 15:20:22 test volumio[978]: info: CorePlayQueue::getTrack 0 Jun 07 15:20:22 test volumio[978]: info: Restoring Previous Volume level: 100 false false Jun 07 15:20:22 test volumio[978]: info: Output device has changed, restarting MPD Jun 07 15:20:22 test sudo[1410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 15:20:22 test volumio[978]: info: Volume configurations have been set Jun 07 15:20:22 test sudo[1410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:22 test sudo[1410]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:22 test sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 15:20:22 test sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 15:20:22 test volumio[978]: info: Updating Volume Controller Parameters: Device: 2 Name: IQaudIO DAC Plus Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 15:20:22 test volumio[978]: info: Disabling external Volume Control Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 15:20:22 test volumio[978]: info: CoreStateMachine::pushState Jun 07 15:20:22 test volumio[978]: info: CorePlayQueue::getTrack 0 Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::volumioPushState Jun 07 15:20:22 test systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 07 15:20:22 test volumio[978]: info: MPD Permissions set Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test systemd[1]: mpd.service: Deactivated successfully. Jun 07 15:20:22 test systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 15:20:22 test systemd[1]: mpd.service: Consumed 2.166s CPU time. Jun 07 15:20:22 test systemd[1]: mpd.socket: Deactivated successfully. Jun 07 15:20:22 test systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 15:20:22 test systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:22 test systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 15:20:22 test systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 15:20:22 test volumio[978]: info: Getting Alsa Cards List without I2S DAC Jun 07 15:20:22 test sudo[1425]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 15:20:22 test sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 15:20:22 test sudo[1425]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 15:20:22 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 15:20:23 test mpd[1433]: 2025-06-07T15:20:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 15:20:24 test systemd[1]: Started mpd.service - Music Player Daemon. Jun 07 15:20:24 test sudo[1412]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:24 test volumio[978]: error: updateQueue error: null Jun 07 15:20:25 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 07 15:20:25 test volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 07 15:20:25 test volumio[978]: info: CoreCommandRouter::volumioGetState Jun 07 15:20:25 test volumio[978]: info: CorePlayQueue::getTrack 0 Jun 07 15:20:26 test volumio[978]: info: VolumeController::SetAlsaVolume100 Jun 07 15:20:26 test volumio[978]: info: CoreStateMachine::pushState Jun 07 15:20:26 test volumio[978]: info: CorePlayQueue::getTrack 0 Jun 07 15:20:26 test volumio[978]: info: CoreCommandRouter::volumioPushState Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources Jun 07 15:20:30 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 15:20:34 test volumio[978]: info: CoreCommandRouter::volumioGetState Jun 07 15:20:34 test volumio[978]: info: CorePlayQueue::getTrack 0 Jun 07 15:20:36 test volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 15:20:36 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 15:20:36 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 07 15:20:36 test sudo[1490]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 07 15:20:36 test sudo[1490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:36 test sudo[1490]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:36 test sudo[1475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 07 15:20:36 test sudo[1475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:36 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 07 15:20:36 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 07 15:20:36 test sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 07 15:20:36 test sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:36 test sudo[1484]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 07 15:20:36 test sudo[1475]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:36 test sudo[1480]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:36 test sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:36 test sudo[1484]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:36 test sudo[1496]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 07 15:20:36 test sudo[1496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:36 test sudo[1499]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 07 15:20:36 test sudo[1499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:20:36 test sudo[1496]: pam_unix(sudo:session): session closed for user root Jun 07 15:20:36 test sudo[1499]: pam_unix(sudo:session): session closed for user root Jun 07 15:21:01 test systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 07 15:21:01 test dbus-daemon[639]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=1535 comm="timedatectl show --property=NTPSynchronized --valu") Jun 07 15:21:01 test systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 07 15:21:01 test dbus-daemon[639]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 07 15:21:01 test systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 07 15:21:01 test setdatetime-helper.sh[1534]: Time is not synchronized. Attempting to sync... Jun 07 15:21:01 test sudo[1543]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 07 Jun 2025 14:21:01 GMT#015' Jun 07 15:21:01 test sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 15:21:01 test setdatetime-helper.sh[1544]: Sat Jun 7 15:21:01 BST 2025 Jun 07 15:21:01 test setdatetime-helper.sh[1534]: Time synchronized successfully. Jun 07 15:21:01 test sudo[1543]: pam_unix(sudo:session): session closed for user root Jun 07 15:21:01 test systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 07 15:21:01 test systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 07 15:21:01 test systemd-journald[330]: Time jumped backwards, rotating. Jun 07 15:21:20 test volumio[978]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Jun 07 15:21:20 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Jun 07 15:21:23 test volumio[978]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Jun 07 15:21:23 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Jun 07 15:21:23 test sudo[1575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jun 07 15:21:23 test sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:21:23 test sudo[1575]: pam_unix(sudo:session): session closed for user root Jun 07 15:21:23 test sudo[1577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jun 07 15:21:23 test sudo[1577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:21:23 test sudo[1577]: pam_unix(sudo:session): session closed for user root Jun 07 15:21:23 test volumio[978]: info: Permissions for /etc/network/interfaces set Jun 07 15:21:23 test volumio[978]: info: Restarting Wireless Network Jun 07 15:21:23 test volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 07 15:21:23 test volumio[978]: info: Discovery: Restarting Advertising due to device name change Jun 07 15:21:23 test sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 07 15:21:23 test sudo[1580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 15:21:23 test systemd[1]: Starting wireless.service - Wireless Services... Jun 07 15:21:24 test sudo[1600]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 07 15:21:24 test sudo[1600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 15:21:24 test sudo[1600]: pam_unix(sudo:session): session closed for user root Jun 07 15:21:24 test sudo[1602]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 07 15:21:24 test sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 15:21:24 test sudo[1602]: pam_unix(sudo:session): session closed for user root Jun 07 15:21:24 test volumio[978]: info: Discovery: A device disappeared from network Jun 07 15:21:24 test volumio[978]: info: Discovery: Device test disappeared from network Jun 07 15:21:24 test volumio[978]: info: Discovery: A device disappeared from network Jun 07 15:21:24 test systemd[1]: wireless.service: Deactivated successfully. Jun 07 15:21:24 test systemd[1]: Started wireless.service - Wireless Services. Jun 07 15:21:24 test systemd[1]: wireless.service: Consumed 2.290s CPU time. Jun 07 15:21:24 test sudo[1580]: pam_unix(sudo:session): session closed for user root Jun 07 15:21:28 test volumio[978]: info: Discovery: Started advertising with name: test Jun 07 15:21:29 test volumio[978]: compat.c: read() failed: Resource temporarily unavailable Jun 07 15:21:29 test volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 15:21:29 test volumio[978]: Error: dns service error: unknown Jun 07 15:21:29 test volumio[978]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 07 15:21:29 test volumio[978]: errorCode: -65537 Jun 07 15:21:29 test volumio[978]: } Jun 07 15:21:29 test volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 15:21:30 test sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-07 15:20' Jun 07 15:21:30 test systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 07 15:21:30 test sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ecd46641d85b721d58d1c7edd71dd0a358c8514c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 7 04:21:21 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="97f38114d6fd5ff4ce2816f49b632994"