-- Logs begin at Thu 2025-09-04 13:09:08 CEST, end at Thu 2025-09-04 13:11:26 CEST. -- Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 04 13:10:00 volumio volumio[1284]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 04 13:10:00 volumio volumio[1284]: info: Completed starting MyVolumio Plugin Sep 04 13:10:00 volumio volumio[1284]: [Metrics] CommandRouter: 32s 309.00ms Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::volumiosetStartupVolume Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::Close All Modals sent Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::Close All Modals sent Sep 04 13:10:00 volumio volumio[1284]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 04 13:10:00 volumio volumio[1284]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 04 13:10:00 volumio volumio[1284]: info: camilladsp stopping service pid 1659... Sep 04 13:10:00 volumio volumio[1284]: info: camilladsp service terminated, instance 1 Sep 04 13:10:00 volumio volumio[1284]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 04 13:10:00 volumio volumio[1284]: info: camilladsp service started and running in background, instance 1 Sep 04 13:10:00 volumio volumio[1284]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 04 13:10:00 volumio volumio[1284]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 04 13:10:00 volumio volumio[1284]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 04 13:10:00 volumio volumio[1284]: error: FusionDsp - WebSocket error: [object Object] Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 04 13:10:00 volumio volumio-remote-updater[841]: No test mode Sep 04 13:10:00 volumio volumio-remote-updater[841]: No alpha test mode Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 04 13:10:00 volumio volumio[1284]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 04 13:10:00 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 04 13:10:01 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 04 13:10:01 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 13:10:01 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 04 13:10:02 volumio go-librespot[1585]: time="2025-09-04T13:10:02+02:00" level=trace msg="sent dealer ping" Sep 04 13:10:02 volumio go-librespot[1585]: time="2025-09-04T13:10:02+02:00" level=trace msg="received dealer pong" Sep 04 13:10:07 volumio volumio[1284]: info: BOOT COMPLETED Sep 04 13:10:16 volumio ntpd[996]: Soliciting pool server 194.182.84.241 Sep 04 13:10:17 volumio ntpd[996]: Soliciting pool server 176.118.170.235 Sep 04 13:10:18 volumio ntpd[996]: Soliciting pool server 162.159.200.1 Sep 04 13:10:18 volumio ntpd[996]: Soliciting pool server 195.58.34.65 Sep 04 13:10:19 volumio ntpd[996]: Soliciting pool server 46.28.110.153 Sep 04 13:10:19 volumio ntpd[996]: Soliciting pool server 188.124.59.142 Sep 04 13:10:19 volumio ntpd[996]: Soliciting pool server 185.3.157.249 Sep 04 13:10:20 volumio ntpd[996]: Soliciting pool server 78.108.96.197 Sep 04 13:10:20 volumio ntpd[996]: Soliciting pool server 81.25.28.124 Sep 04 13:10:21 volumio ntpd[996]: Soliciting pool server 89.203.251.72 Sep 04 13:10:21 volumio ntpd[996]: Soliciting pool server 85.163.168.227 Sep 04 13:10:21 volumio ntpd[996]: Soliciting pool server 194.182.77.109 Sep 04 13:10:22 volumio ntpd[996]: Soliciting pool server 109.224.72.175 Sep 04 13:10:22 volumio ntpd[996]: Soliciting pool server 2606:4700:f1::123 Sep 04 13:10:23 volumio volumio[1284]: error: error Sep 04 13:10:23 volumio volumio[1284]: error: error Sep 04 13:10:23 volumio volumio[1284]: error: error Sep 04 13:10:25 volumio ntpd[996]: receive: Unexpected origin timestamp 0xec63f221.0bb1dfbe does not match aorg 0000000000.00000000 from server@81.25.28.124 xmt 0xec63f221.b660b408 Sep 04 13:10:25 volumio ntpd[996]: receive: Unexpected origin timestamp 0xec63f221.0bb5b88d does not match aorg 0000000000.00000000 from server@194.182.84.241 xmt 0xec63f221.b65f466a Sep 04 13:10:25 volumio ntpd[996]: receive: Unexpected origin timestamp 0xec63f221.0bb3eba4 does not match aorg 0000000000.00000000 from server@185.3.157.249 xmt 0xec63f221.b7346f64 Sep 04 13:10:26 volumio volumio[1284]: error: error Sep 04 13:10:32 volumio go-librespot[1585]: time="2025-09-04T13:10:32+02:00" level=trace msg="sent dealer ping" Sep 04 13:10:32 volumio go-librespot[1585]: time="2025-09-04T13:10:32+02:00" level=trace msg="received dealer pong" Sep 04 13:11:02 volumio go-librespot[1585]: time="2025-09-04T13:11:02+02:00" level=trace msg="sent dealer ping" Sep 04 13:11:02 volumio go-librespot[1585]: time="2025-09-04T13:11:02+02:00" level=trace msg="received dealer pong" Sep 04 13:11:14 volumio volumio[1284]: error: error Sep 04 13:11:14 volumio volumio[1284]: error: error Sep 04 13:11:14 volumio volumio[1284]: error: error Sep 04 13:11:14 volumio volumio[1284]: error: error Sep 04 13:11:16 volumio volumio[1284]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 04 13:11:16 volumio volumio[1284]: info: Preparing to save Alsa Options, stopping services first Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Sep 04 13:11:16 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::volumioPause Sep 04 13:11:16 volumio volumio[1284]: info: CoreStateMachine::pause Sep 04 13:11:16 volumio volumio[1284]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"rpi-dacplus","label":"Raspberry Pi DAC+"}} Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: Enabling I2S DAC: Raspberry Pi DAC+ Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Sep 04 13:11:16 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio volumio[1284]: info: No Overlays Loaded Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio sudo[2432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rpi-dacplus Sep 04 13:11:16 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a0000/status Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/#address-cells Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/#size-cells Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/status Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/card_name Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/dai_name Sep 04 13:11:16 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/dai_stream_name Sep 04 13:11:16 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio volumio[1284]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: Found match in i2s Card Database: setting mixer Digital for card Raspberry Pi DAC+ Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 04 13:11:16 volumio systemd-udevd[2442]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Sep 04 13:11:16 volumio volumio[1284]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 04 13:11:16 volumio volumio[1284]: info: Disabling external Volume Control Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 13:11:16 volumio volumio[1284]: info: Enable softmixer device for audio device undefined Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 04 13:11:16 volumio volumio[1284]: info: I2S Param [object Object] successfully enabled Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 13:11:16 volumio volumio[1284]: info: Getting Alsa Cards List without I2S DAC Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 04 13:11:16 volumio volumio[1284]: info: Software Volume ALSA configuration written Sep 04 13:11:16 volumio volumio[1284]: info: Preparing to generate the ALSA configuration file Sep 04 13:11:16 volumio volumio[1284]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 04 13:11:16 volumio volumio[1284]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 04 13:11:16 volumio volumio[1284]: info: Reading ALSA contributions from plugins. Sep 04 13:11:16 volumio volumio[1284]: info: VolumeController:: Volume=100 Mute =false Sep 04 13:11:16 volumio volumio[1284]: info: CoreStateMachine::pushState Sep 04 13:11:16 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Sep 04 13:11:16 volumio volumio[1284]: info: [ASDebug] CurState: stop PrevState: stop Sep 04 13:11:16 volumio volumio[1284]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 04 13:11:16 volumio volumio[1284]: info: Asound.conf file written Sep 04 13:11:16 volumio sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 04 13:11:16 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio volumio[1284]: No state is present for card DAC Sep 04 13:11:16 volumio volumio[1284]: Found hardware: "RPi_DAC_" "" "" "" "" Sep 04 13:11:16 volumio volumio[1284]: Hardware is initialized using a generic method Sep 04 13:11:16 volumio volumio[1284]: No state is present for card DAC Sep 04 13:11:16 volumio volumio[1284]: info: Output device has changed, restarting MPD Sep 04 13:11:16 volumio sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 13:11:16 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio volumio[1284]: info: Output device has changed, restarting Shairport Sync Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 13:11:16 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio systemd[1]: Stopping Music Player Daemon... Sep 04 13:11:16 volumio volumio[1284]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 13:11:16 volumio volumio[1284]: info: Output device has changed, restarting MPD Sep 04 13:11:16 volumio systemd[1]: mpd.service: Succeeded. Sep 04 13:11:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 04 13:11:16 volumio sudo[2493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 13:11:16 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio volumio[1284]: info: Output device has changed, restarting Shairport Sync Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio systemd[1]: Starting Music Player Daemon... Sep 04 13:11:16 volumio sudo[2496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 13:11:16 volumio sudo[2496]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio volumio[1284]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 13:11:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 04 13:11:16 volumio systemd[1]: mpd.service: Succeeded. Sep 04 13:11:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 04 13:11:16 volumio volumio[1284]: info: MPD Permissions set Sep 04 13:11:16 volumio volumio[1284]: info: MPD Permissions set Sep 04 13:11:16 volumio systemd[1]: Starting Music Player Daemon... Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio sudo[2501]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 04 13:11:16 volumio sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 13:11:16 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 13:11:16 volumio sudo[2501]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio volumio[1284]: info: Starting Shairport Sync Sep 04 13:11:16 volumio volumio[1284]: info: Starting Shairport Sync Sep 04 13:11:16 volumio sudo[2513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 13:11:16 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 13:11:16 volumio sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 04 13:11:16 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 04 13:11:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 04 13:11:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 13:11:16 volumio sudo[2516]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:16 volumio volumio[1284]: info: Shairport-Sync Started Sep 04 13:11:16 volumio volumio[1284]: info: Shairport-Sync Started Sep 04 13:11:16 volumio mpd[2511]: Sep 04 13:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 13:11:16 volumio systemd[1]: Started Music Player Daemon. Sep 04 13:11:17 volumio sudo[2496]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:17 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:17 volumio volumio[1284]: error: MPD error: The expression evaluated to a falsy value: Sep 04 13:11:17 volumio volumio[1284]: assert.ok(self.idling) Sep 04 13:11:17 volumio volumio[1284]: error: The expression evaluated to a falsy value: Sep 04 13:11:17 volumio volumio[1284]: assert.ok(self.idling) Sep 04 13:11:17 volumio volumio[1284]: error: updateQueue error: null Sep 04 13:11:20 volumio volumio[1284]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 04 13:11:20 volumio volumio[1284]: info: CoreStateMachine::getcurrentVolume Sep 04 13:11:20 volumio volumio[1284]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 13:11:20 volumio volumio[1284]: info: VolumeController:: Volume=100 Mute =false Sep 04 13:11:20 volumio volumio[1284]: info: CoreStateMachine::pushState Sep 04 13:11:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Sep 04 13:11:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Sep 04 13:11:20 volumio volumio[1284]: info: CoreStateMachine::updateTrackBlock Sep 04 13:11:20 volumio volumio[1284]: info: CorePlayQueue::getTrackBlock Sep 04 13:11:20 volumio volumio[1284]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 13:11:20 volumio volumio[1284]: info: [ASDebug] CurState: stop PrevState: stop Sep 04 13:11:20 volumio volumio[1284]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 04 13:11:20 volumio volumio[1284]: info: VolumeController:: Volume=100 Mute =false Sep 04 13:11:20 volumio volumio[1284]: info: CoreStateMachine::pushState Sep 04 13:11:20 volumio volumio[1284]: info: CorePlayQueue::getTrack 0 Sep 04 13:11:20 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Sep 04 13:11:20 volumio volumio[1284]: info: [ASDebug] CurState: stop PrevState: stop Sep 04 13:11:20 volumio volumio[1284]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 04 13:11:26 volumio volumio[1284]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Sep 04 13:11:26 volumio volumio[1284]: info: PLUGIN onReboot : networkfs Sep 04 13:11:26 volumio sudo[2542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/music Sep 04 13:11:26 volumio sudo[2542]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 13:11:26 volumio systemd[1]: mnt-NAS-music.mount: Succeeded. Sep 04 13:11:26 volumio sudo[2542]: pam_unix(sudo:session): session closed for user root Sep 04 13:11:26 volumio volumio[1284]: info: PLUGIN onReboot : audiophonicsonoff Sep 04 13:11:26 volumio volumio[1284]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 13:11:26 volumio volumio[1284]: TypeError: Cannot read property 'writeSync' of undefined Sep 04 13:11:26 volumio volumio[1284]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Sep 04 13:11:26 volumio volumio[1284]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Sep 04 13:11:26 volumio volumio[1284]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Sep 04 13:11:26 volumio volumio[1284]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 04 13:11:26 volumio volumio[1284]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 04 13:11:26 volumio volumio[1284]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Sep 04 13:11:26 volumio volumio[1284]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Sep 04 13:11:26 volumio volumio[1284]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Sep 04 13:11:26 volumio volumio[1284]: at Socket.emit (events.js:315:20) Sep 04 13:11:26 volumio volumio[1284]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 04 13:11:26 volumio volumio[1284]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 04 13:11:26 volumio volumio[1284]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 13:11:26 volumio sudo[2554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 13:10 Sep 04 13:11:26 volumio sudo[2554]: 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"