-- Logs begin at Tue 2024-10-22 23:30:43 +05, end at Wed 2024-10-23 09:49:29 +05. -- Oct 23 09:48:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 23 09:48:16 volumio kernel: hwmon hwmon1: Voltage normalised Oct 23 09:48:47 volumio volumio[2570]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 23 09:48:47 volumio volumio[2570]: info: Preparing to save Alsa Options, stopping services first Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:47 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::volumioPause Oct 23 09:48:47 volumio volumio[2570]: info: CoreStateMachine::pause Oct 23 09:48:47 volumio volumio[2570]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"NAD USB Audio 2.0"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:47 volumio volumio[2570]: info: Setting mixer Analogue 18 for card NAD USB Audio 2.0 Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 23 09:48:47 volumio volumio[2570]: info: Updating Volume Controller Parameters: Device: 5 Name: NAD USB Audio 2.0 Mixer: Analogue 18 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 23 09:48:47 volumio volumio[2570]: info: Disabling external Volume Control Oct 23 09:48:47 volumio volumio[2570]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 09:48:47 volumio volumio[2570]: info: Preparing to generate the ALSA configuration file Oct 23 09:48:47 volumio volumio[2570]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 23 09:48:47 volumio volumio[2570]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 23 09:48:47 volumio volumio[2570]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 23 09:48:47 volumio volumio[2570]: info: Reading ALSA contributions from plugins. Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 23 09:48:48 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:48 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:48 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:48 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:48 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:48 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:48 volumio volumio[2570]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 23 09:48:48 volumio volumio[2570]: info: Preparing to save Alsa Options, stopping services first Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:48 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::volumioPause Oct 23 09:48:48 volumio volumio[2570]: info: CoreStateMachine::pause Oct 23 09:48:48 volumio volumio[2570]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"NAD USB Audio 2.0"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:48 volumio sudo[8730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:48 volumio sudo[8730]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:48 volumio sudo[8730]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:48 volumio volumio[2570]: info: Setting mixer Analogue 18 for card NAD USB Audio 2.0 Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 23 09:48:48 volumio volumio[2570]: info: Updating Volume Controller Parameters: Device: 5 Name: NAD USB Audio 2.0 Mixer: Analogue 18 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 23 09:48:48 volumio volumio[2570]: info: Disabling external Volume Control Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 09:48:48 volumio volumio[2570]: info: Asound.conf file unchanged, so no further update is needed Oct 23 09:48:48 volumio volumio[2570]: info: Output device has changed, restarting MPD Oct 23 09:48:48 volumio sudo[8742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 09:48:48 volumio sudo[8742]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:48 volumio sudo[8742]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:48 volumio volumio[2570]: info: Output device has changed, restarting Shairport Sync Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:48 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:48 volumio sudo[8746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 09:48:48 volumio sudo[8746]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:48 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 23 09:48:48 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 23 09:48:48 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 23 09:48:48 volumio systemd[1]: Stopping Music Player Daemon... Oct 23 09:48:49 volumio volumio[2570]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 09:48:49 volumio volumio[2570]: info: Preparing to generate the ALSA configuration file Oct 23 09:48:49 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:49 volumio volumio[2570]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 23 09:48:49 volumio volumio[2570]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 23 09:48:49 volumio volumio[2570]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 23 09:48:49 volumio volumio[2570]: info: Reading ALSA contributions from plugins. Oct 23 09:48:49 volumio volumio[2570]: info: MPD Permissions set Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:49 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:49 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:49 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:49 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:49 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:49 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:49 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:49 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:50 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:50 volumio volumio[2570]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 23 09:48:50 volumio volumio[2570]: info: Preparing to save Alsa Options, stopping services first Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:50 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::volumioPause Oct 23 09:48:50 volumio volumio[2570]: info: CoreStateMachine::pause Oct 23 09:48:50 volumio volumio[2570]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"NAD USB Audio 2.0"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:50 volumio sudo[8762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:50 volumio sudo[8762]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:50 volumio sudo[8762]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:50 volumio volumio[2570]: info: Setting mixer Analogue 18 for card NAD USB Audio 2.0 Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 23 09:48:50 volumio volumio[2570]: info: Updating Volume Controller Parameters: Device: 5 Name: NAD USB Audio 2.0 Mixer: Analogue 18 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 23 09:48:50 volumio volumio[2570]: info: Disabling external Volume Control Oct 23 09:48:50 volumio volumio[2570]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 09:48:50 volumio volumio[2570]: info: Starting Shairport Sync Oct 23 09:48:50 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:51 volumio sudo[8774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 09:48:51 volumio sudo[8774]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 23 09:48:51 volumio volumio[2570]: info: Asound.conf file unchanged, so no further update is needed Oct 23 09:48:51 volumio volumio[2570]: info: Output device has changed, restarting MPD Oct 23 09:48:51 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 23 09:48:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 23 09:48:51 volumio sudo[8778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 09:48:51 volumio sudo[8778]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:51 volumio volumio[2570]: info: Output device has changed, restarting Shairport Sync Oct 23 09:48:51 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:51 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:51 volumio sudo[8778]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 23 09:48:51 volumio sudo[8774]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:51 volumio sudo[8782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 09:48:51 volumio sudo[8782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:51 volumio volumio[2570]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 09:48:51 volumio volumio[2570]: info: Preparing to generate the ALSA configuration file Oct 23 09:48:51 volumio volumio[2570]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 23 09:48:51 volumio volumio[2570]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Oct 23 09:48:51 volumio volumio[2570]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 23 09:48:51 volumio volumio[2570]: info: Reading ALSA contributions from plugins. Oct 23 09:48:51 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 23 09:48:52 volumio systemd[1]: mpd.service: Succeeded. Oct 23 09:48:52 volumio systemd[1]: Stopped Music Player Daemon. Oct 23 09:48:52 volumio volumio[2570]: info: MPD Permissions set Oct 23 09:48:52 volumio volumio[2570]: info: Shairport-Sync Started Oct 23 09:48:52 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:52 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:52 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:52 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:52 volumio systemd[1]: Starting Music Player Daemon... Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 23 09:48:52 volumio volumio[2570]: info: CoreStateMachine::getcurrentVolume Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 09:48:52 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:52 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:52 volumio sudo[8794]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 23 09:48:52 volumio sudo[8794]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:52 volumio sudo[8794]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:52 volumio sudo[8801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:52 volumio sudo[8801]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio sudo[8801]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 23 09:48:52 volumio volumio[2570]: info: CoreStateMachine::getcurrentVolume Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 09:48:52 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:52 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:52 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:52 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:52 volumio volumio[2570]: info: CoreStateMachine::updateTrackBlock Oct 23 09:48:52 volumio volumio[2570]: info: CorePlayQueue::getTrackBlock Oct 23 09:48:52 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 09:48:53 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:53 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:53 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:53 volumio sudo[8812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:53 volumio sudo[8812]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:53 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:53 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:53 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:53 volumio sudo[8812]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:53 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:53 volumio volumio[2570]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 23 09:48:53 volumio volumio[2570]: info: Starting Shairport Sync Oct 23 09:48:53 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:53 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:53 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:53 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:53 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:53 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:53 volumio volumio[2570]: info: CoreStateMachine::updateTrackBlock Oct 23 09:48:53 volumio volumio[2570]: info: CorePlayQueue::getTrackBlock Oct 23 09:48:53 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 09:48:53 volumio sudo[8819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 09:48:53 volumio sudo[8819]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 23 09:48:53 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 23 09:48:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 23 09:48:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 23 09:48:53 volumio sudo[8819]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:53 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:53 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:53 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:54 volumio sudo[8829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:54 volumio sudo[8829]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:54 volumio volumio[2570]: info: Shairport-Sync Started Oct 23 09:48:54 volumio volumio[2570]: error: [squeezelite_mc] Could not query supported ALSA formats for card 5 because device is busy Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 09:48:54 volumio sudo[8829]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:54 volumio volumio[2570]: info: Asound.conf file unchanged, so no further update is needed Oct 23 09:48:54 volumio volumio[2570]: info: Output device has changed, restarting MPD Oct 23 09:48:54 volumio sudo[8833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 23 09:48:54 volumio sudo[8833]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:54 volumio sudo[8833]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:54 volumio volumio[2570]: info: Output device has changed, restarting Shairport Sync Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:54 volumio sudo[8836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 23 09:48:54 volumio sudo[8836]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:54 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 23 09:48:54 volumio systemd[1]: mpd.service: Succeeded. Oct 23 09:48:54 volumio systemd[1]: Stopped Music Player Daemon. Oct 23 09:48:54 volumio volumio[2570]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 23 09:48:54 volumio systemd[1]: Starting Music Player Daemon... Oct 23 09:48:54 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:54 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:54 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:54 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:54 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:54 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:54 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:54 volumio volumio[2570]: info: MPD Permissions set Oct 23 09:48:54 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:54 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:54 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:54 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:54 volumio sudo[8841]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 23 09:48:54 volumio sudo[8841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 23 09:48:54 volumio volumio[2570]: info: CoreStateMachine::getcurrentVolume Oct 23 09:48:54 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 09:48:54 volumio sudo[8847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:54 volumio sudo[8847]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:54 volumio sudo[8841]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:55 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:55 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:55 volumio sudo[8847]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:55 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:48:55 volumio sudo[8853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:55 volumio sudo[8853]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:55 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:55 volumio sudo[8853]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:55 volumio sudo[8856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:48:55 volumio sudo[8856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:55 volumio sudo[8856]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:55 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:55 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:55 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:55 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:55 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:55 volumio volumio[2570]: info: CoreStateMachine::updateTrackBlock Oct 23 09:48:55 volumio volumio[2570]: info: CorePlayQueue::getTrackBlock Oct 23 09:48:55 volumio volumio[2570]: info: CoreCommandRouter::volumioRetrievevolume Oct 23 09:48:55 volumio volumio[2570]: info: Starting Shairport Sync Oct 23 09:48:56 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:56 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:56 volumio sudo[8865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 23 09:48:56 volumio sudo[8865]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 23 09:48:56 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 23 09:48:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 23 09:48:56 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:56 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:56 volumio volumio[2570]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 23 09:48:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 23 09:48:56 volumio sudo[8869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:56 volumio sudo[8869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:56 volumio sudo[8865]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:56 volumio sudo[8869]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:56 volumio volumio[2570]: info: CALLMETHOD: music_service squeezelite_mc configSwitchToManualSqueezeliteSettings Oct 23 09:48:56 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: squeezelite_mc , configSwitchToManualSqueezeliteSettings Oct 23 09:48:56 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:56 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:56 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:56 volumio volumio[2570]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"5","mixerType":"Hardware","mixer":"Analogue 18"} Oct 23 09:48:56 volumio volumio[2570]: 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 Oct 23 09:48:56 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:56 volumio volumio[2570]: info: Shairport-Sync Started Oct 23 09:48:56 volumio volumio[2570]: error: [squeezelite_mc] Could not query supported ALSA formats for card 5 because device is busy Oct 23 09:48:56 volumio volumio[2570]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 09:48:56 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:48:57 volumio volumio[2570]: info: VolumeController:: Volume=100 Mute =false Oct 23 09:48:57 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:48:57 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:57 volumio volumio[2570]: info: CoreStateMachine::pushState Oct 23 09:48:57 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:48:57 volumio volumio[2570]: info: CoreCommandRouter::volumioPushState Oct 23 09:48:57 volumio sudo[8877]: volumio : 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 Oct 23 09:48:57 volumio sudo[8877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:57 volumio volumio[2570]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 23 09:48:57 volumio sudo[8880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:48:57 volumio sudo[8880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:57 volumio volumio[2570]: info: PeppySpectrum ---peppyspectrum status stop Oct 23 09:48:57 volumio sudo[8880]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:57 volumio sudo[8877]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:57 volumio volumio[2570]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Oct 23 09:48:57 volumio sudo[8885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 23 09:48:57 volumio sudo[8885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:57 volumio sudo[8885]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:57 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:57 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:57 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:48:57 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:48:57 volumio volumio[2570]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 23 09:48:57 volumio sudo[8889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Oct 23 09:48:57 volumio sudo[8889]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:57 volumio volumio[2570]: info: peppyspectrum Daemon Stop Oct 23 09:48:57 volumio volumio[2570]: error: [squeezelite_mc] Could not query supported ALSA formats for card 5 because device is busy Oct 23 09:48:57 volumio sudo[8889]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:57 volumio volumio[2570]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 23 09:48:58 volumio sudo[8895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 23 09:48:58 volumio sudo[8895]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:58 volumio sudo[8895]: pam_unix(sudo:session): session closed for user root Oct 23 09:48:58 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Oct 23 09:48:58 volumio sudo[8898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 23 09:48:58 volumio sudo[8898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:48:58 volumio systemd[1]: Reloading. Oct 23 09:48:58 volumio mpd[8850]: Oct 23 09:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 23 09:48:59 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. Oct 23 09:49:00 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 23 09:49:00 volumio systemd[1]: Started Music Player Daemon. Oct 23 09:49:00 volumio sudo[8746]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:00 volumio sudo[8782]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:00 volumio sudo[8898]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:00 volumio sudo[8836]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:00 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 23 09:49:00 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:49:00 volumio sudo[8925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:49:00 volumio sudo[8925]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:00 volumio volumio[2570]: error: MPD error: The expression evaluated to a falsy value: Oct 23 09:49:00 volumio volumio[2570]: assert.ok(self.idling) Oct 23 09:49:00 volumio volumio[2570]: error: The expression evaluated to a falsy value: Oct 23 09:49:00 volumio volumio[2570]: assert.ok(self.idling) Oct 23 09:49:00 volumio volumio[2570]: error: MPD error: The expression evaluated to a falsy value: Oct 23 09:49:00 volumio volumio[2570]: assert.ok(self.idling) Oct 23 09:49:00 volumio volumio[2570]: error: The expression evaluated to a falsy value: Oct 23 09:49:00 volumio volumio[2570]: assert.ok(self.idling) Oct 23 09:49:00 volumio volumio[2570]: error: updateQueue error: null Oct 23 09:49:00 volumio sudo[8925]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:00 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Oct 23 09:49:00 volumio sudo[8928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Oct 23 09:49:00 volumio sudo[8928]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:00 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Oct 23 09:49:00 volumio kernel: process '/opt/squeezelite/squeezelite' started with executable stack Oct 23 09:49:00 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 23 09:49:00 volumio sudo[8928]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:01 volumio volumio[2570]: info: camilladsp spawned new process with pid 8932, instance 1, run: true Oct 23 09:49:01 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:49:01 volumio volumio[2570]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 23 09:49:01 volumio volumio[2570]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 23 09:49:01 volumio volumio[2570]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 23 09:49:01 volumio sudo[8937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:49:01 volumio sudo[8937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:02 volumio sudo[8937]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:02 volumio volumio[2570]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 23 09:49:02 volumio volumio[8915]: cset: --> shielding system active with Oct 23 09:49:02 volumio volumio[8915]: cset: "system" cpuset of CPUSPEC(1-3) with 257 tasks running Oct 23 09:49:02 volumio volumio[8915]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 23 09:49:02 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:49:02 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 23 09:49:02 volumio sudo[8954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:49:02 volumio sudo[8954]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:02 volumio sudo[8954]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:03 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:49:03 volumio sudo[8957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:49:03 volumio sudo[8957]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:03 volumio sudo[8957]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:04 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:49:04 volumio sudo[8966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:49:04 volumio sudo[8966]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:04 volumio sudo[8966]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:04 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:49:04 volumio sudo[8970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:49:04 volumio sudo[8970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:04 volumio sudo[8970]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:05 volumio volumio[2570]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 09:49:05 volumio volumio[2570]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 23 09:49:05 volumio sudo[8973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 23 09:49:05 volumio sudo[8973]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:05 volumio sudo[8973]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:05 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:49:05 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:49:05 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 09:49:05 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 23 09:49:05 volumio volumio[2570]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 23 09:49:05 volumio volumio[2570]: error: [squeezelite_mc] Could not query supported ALSA formats for card 5 because device is busy Oct 23 09:49:08 volumio kernel: hwmon hwmon1: Voltage normalised Oct 23 09:49:11 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 09:49:11 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 09:49:11 volumio volumio[2570]: info: Discovery: Getting this device information Oct 23 09:49:11 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:11 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:11 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 09:49:11 volumio volumio[2570]: verbose: New Socket.io Connection to 192.168.0.198:3000 from 192.168.0.24 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Oct 23 09:49:11 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:11 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:11 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 23 09:49:11 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 09:49:12 volumio volumio[2570]: info: Discovery: Getting this device information Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:12 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 09:49:12 volumio volumio[2570]: info: Discovery: Getting this device information Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:12 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:12 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 09:49:15 volumio sudo[8979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 09:49:15 volumio sudo[8979]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:15 volumio sudo[8979]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:15 volumio sudo[8982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 09:49:15 volumio sudo[8982]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:15 volumio sudo[8982]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:15 volumio volumio[2570]: verbose: New Socket.io Connection to 192.168.0.217 from 192.168.0.24 UA: Mozilla/5.0 (Linux; Android 14; 22081212UG Build/UKQ1.230917.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Oct 23 09:49:15 volumio sudo[8985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 09:49:15 volumio sudo[8985]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:15 volumio sudo[8985]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:15 volumio sudo[8988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 09:49:15 volumio sudo[8988]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 09:49:15 volumio sudo[8988]: pam_unix(sudo:session): session closed for user root Oct 23 09:49:15 volumio volumio[2570]: verbose: New Socket.io Connection to 192.168.0.217 from 192.168.0.24 UA: Mozilla/5.0 (Linux; Android 14; 22081212UG Build/UKQ1.230917.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::volumioGetVisibleSources Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:16 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 23 09:49:16 volumio volumio[2570]: info: Received Get System Info Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 09:49:16 volumio volumio[2570]: info: Discovery: Getting this device information Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:16 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:16 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:16 volumio volumio[2570]: info: Listing playlists Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 23 09:49:16 volumio volumio[2570]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 23 09:49:17 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 23 09:49:17 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 09:49:17 volumio volumio[2570]: info: Received Get System Info Oct 23 09:49:17 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 09:49:17 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 09:49:17 volumio volumio[2570]: info: Discovery: Getting this device information Oct 23 09:49:17 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:17 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:17 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 09:49:18 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 23 09:49:19 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 09:49:19 volumio volumio[2570]: info: Received Get System Info Oct 23 09:49:19 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 09:49:19 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 09:49:19 volumio volumio[2570]: info: Discovery: Getting this device information Oct 23 09:49:19 volumio volumio[2570]: info: CoreCommandRouter::volumioGetState Oct 23 09:49:19 volumio volumio[2570]: info: CorePlayQueue::getTrack 0 Oct 23 09:49:19 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 09:49:23 volumio kernel: hwmon hwmon1: Voltage normalised Oct 23 09:49:25 volumio volumio[2570]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 23 09:49:28 volumio volumio[2570]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 23 09:49:28 volumio volumio[2570]: info: PLUGIN onReboot : networkfs Oct 23 09:49:28 volumio volumio[2570]: info: PLUGIN onReboot : audiophonicsonoff Oct 23 09:49:28 volumio volumio[2570]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 09:49:28 volumio volumio[2570]: TypeError: Cannot read property 'writeSync' of undefined Oct 23 09:49:28 volumio volumio[2570]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 23 09:49:28 volumio volumio[2570]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 23 09:49:28 volumio volumio[2570]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 23 09:49:28 volumio volumio[2570]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 23 09:49:28 volumio volumio[2570]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 23 09:49:28 volumio volumio[2570]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 23 09:49:28 volumio volumio[2570]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 23 09:49:28 volumio volumio[2570]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Oct 23 09:49:28 volumio volumio[2570]: at Socket.emit (events.js:315:20) Oct 23 09:49:28 volumio volumio[2570]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 23 09:49:28 volumio volumio[2570]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 23 09:49:28 volumio volumio[2570]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 09:49:29 volumio sudo[9004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-23 09:48 Oct 23 09:49:29 volumio sudo[9004]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"