[PROBLEM] MPD and CamillaDSP errors

[gwurb]

The moOde startup log suggests some sort of network issue. An Ethernet connected Pi should get an IP address from Router almost instantly during the Linux part of system startup and before moOde startup but the moOde startup log shows "eth0 address not assigned".

Code:

20230116 114012 worker: eth0 adapter exists
20230116 114012 worker: eth0 check for address (Off)
20230116 114012 worker: eth0 address not assigned
20230116 114012 worker: wlan0 adapter does not exist
20230116 114012 worker: apd0 router mode (Off)
20230116 114012 worker: No active network interface

You could try turning on "Wait for Ethernet" in System config and leave the timeout at 90 sec default. This will cause moOde startup to wait up to 90 seconds for an Ethernet address to be assigned but this may just mask an underlying network issue.

The lines from Camilla in the MPD log indicate no audio device detected.

Code:

Jan 16 11:43 : output: Failed to play on "ALSA Default" (alsa): snd_pcm_poll_descriptors_revents() failed: No such device
Jan 16 11:43 : exception: Failed to open audio output

No USB hub in the chain anymore...

The wait for ethernet works...

Code:

20230116 122918 worker: --
20230116 122918 worker: -- Start moOde 8 series
20230116 122918 worker: --
20230116 122918 worker: Successfully daemonized
20230116 122920 worker: File check (OK)
20230116 122921 worker: Session vacuumed
20230116 122921 worker: Session loaded
20230116 122921 worker: Debug logging (OFF)
20230116 122921 worker: --
20230116 122921 worker: -- Audio debug
20230116 122921 worker: --
20230116 122921 worker: ALSA cards: (0:Loopback | 1:b1 | 2:Headphones | 3:M4
20230116 122921 worker: MPD config: (3:M4 | mixer:(PCM) | card:3)
20230116 122921 worker: ALSA mixer actual (PCM)
20230116 122921 worker: ALSA PCM volume set to (amixer: Unable to find simple control 'PCM',0)
20230116 122921 worker: --
20230116 122921 worker: -- System
20230116 122921 worker: --
20230116 122922 worker: Host      (mooderp4)
20230116 122922 worker: moOde     (8.2.4 2022-12-27)
20230116 122922 worker: RaspiOS   (11.6)
20230116 122922 worker: Kernel    (5.15.76-v8+ #1597)
20230116 122922 worker: Platform  (Pi-4B 1.2 4GB)
20230116 122922 worker: ARM arch  (aarch64, 64-bit)
20230116 122922 worker: MPD ver   (0.23.11)
20230116 122922 worker: CPU gov   (ondemand)
20230116 122922 worker: USB boot  (enabled)
20230116 122922 worker: File sys  (expanded)
20230116 122923 worker: HDMI port (On)
20230116 122923 worker: Sys LED0  (On)
20230116 122923 worker: Sys LED1  (On)
20230116 122923 worker: --
20230116 122923 worker: -- Network
20230116 122923 worker: --
20230116 122923 worker: eth0 adapter exists
20230116 122923 worker: eth0 check for address (On)
20230116 122923 worker: eth0 address check (90 secs)
20230116 122923 worker: eth0 check 0 for IP address
20230116 122925 worker: eth0 check 1 for IP address
20230116 122927 worker: eth0 check 2 for IP address
20230116 122929 worker: eth0 check 3 for IP address
20230116 122931 worker: eth0 check 4 for IP address
20230116 122952 worker: IP addr (192.168.1.28)
20230116 122952 worker: Netmask (255.255.255.0)
20230116 122952 worker: Gateway (192.168.1.1)
20230116 122952 worker: Pri DNS (192.168.1.1)
20230116 122952 worker: Domain  ()
20230116 122952 worker: wlan0 adapter does not exist
20230116 122952 worker: apd0 router mode (Off)
20230116 122952 worker: --
20230116 122952 worker: -- Software update
20230116 122952 worker: --
20230116 122952 worker: Automatic check (On)
20230116 122952 worker: Checking for available update...
20230116 122952 worker: Software is up to date
20230116 122952 worker: --
20230116 122952 worker: -- Audio config
20230116 122952 worker: --
20230116 122952 worker: MPD conf update skipped (USB device)
20230116 122952 worker: ALSA card number (3)
20230116 122952 worker: MPD audio output (M4)
20230116 122953 worker: Audio formats (S32_LE)
20230116 122953 worker: ALSA mixer name (PCM)
20230116 122953 worker: MPD mixer type (software)
20230116 122953 worker: Hdwr volume controller not detected
20230116 122953 worker: ALSA output mode (Default: plughw)
20230116 122953 worker: ALSA loopback (Off)
20230116 122953 worker: Reset renderer active flags
20230116 122953 worker: CamillaDSP (MOTU4.yml)
20230116 122953 worker: --
20230116 122953 worker: -- File sharing
20230116 122953 worker: --
20230116 122953 worker: SMB file sharing (Off)
20230116 122953 worker: NFS file sharing (Off)
20230116 122953 worker: --
20230116 122953 worker: -- MPD startup
20230116 122953 worker: --
20230116 122953 worker: MPD started
20230116 122953 worker: MPD accepting connections
20230116 122953 worker: MPD output 1 ALSA Default (On)
20230116 122953 worker: MPD output 2 ALSA Bluetooth (Off)
20230116 122953 worker: MPD output 3 HTTP Server (Off)
20230116 122953 worker: MPD crossfade (off)
20230116 122953 worker: MPD ignore CUE files (yes)
20230116 122953 worker: --
20230116 122953 worker: -- Music sources
20230116 122953 worker: --
20230116 122953 worker: USB sources
20230116 122953 worker: No drives found
20230116 122953 worker: NAS sources
20230116 122953 worker: Music

I don't know why the system suddenly decides that there is no audio device detected. Can you think of anything that Moode is doing that may be contributing?

CamillaDSP log for most recent reboot

Code:

2023-01-16 01:33:18.627324 INFO [src/bin.rs:711] CamillaDSP version 1.0.3
2023-01-16 01:33:18.627439 INFO [src/bin.rs:712] Running on linux, aarch64
2023-01-16 01:33:18.658983 INFO [src/alsadevice.rs:161] Starting playback from Prepared state
Jan 16 12:33 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: stream 0, timescale not set
Jan 16 12:33 : ffmpeg/mov,mp4,m4a,3gp,3g2,mj2: stream 0, timescale not set
2023-01-16 01:33:58.403430 WARN [src/alsadevice.rs:157] Prepare playback after buffer underrun
2023-01-16 01:33:58.510386 WARN [src/alsadevice.rs:192] Wait timed out, playback device takes too long to drain buffer
2023-01-16 01:33:58.510499 WARN [src/alsadevice.rs:213] Retrying playback, error: ALSA function 'snd_pcm_writei' failed with error 'EAGAIN: Try again'
2023-01-16 01:33:58.531963 ERROR [src/bin.rs:344] Playback error: ALSA function 'snd_pcm_writei' failed with error 'EAGAIN: Try again'
2023-01-16 01:33:58.616497 WARN [src/alsadevice.rs:192] Wait timed out, playback device takes too long to drain buffer
2023-01-16 01:33:58.616750 WARN [src/alsadevice.rs:213] Retrying playback, error: ALSA function 'snd_pcm_writei' failed with error 'EAGAIN: Try again'
2023-01-16 01:33:58.723168 WARN [src/alsadevice.rs:192] Wait timed out, playback device takes too long to drain buffer
2023-01-16 01:33:58.723287 WARN [src/alsadevice.rs:213] Retrying playback, error: ALSA function 'snd_pcm_writei' failed with error 'EAGAIN: Try again'
Jan 16 12:33 : output: Failed to play on "ALSA Default" (alsa): snd_pcm_poll_descriptors_revents() failed: No such device
Jan 16 12:33 : exception: Failed to open audio output
2023-01-16 01:34:31.370157 INFO [src/bin.rs:711] CamillaDSP version 1.0.3
2023-01-16 01:34:31.370299 INFO [src/bin.rs:712] Running on linux, aarch64
2023-01-16 01:34:31.384324 INFO [src/alsadevice.rs:161] Starting playback from Prepared state
2023-01-16 01:34:32.580022 INFO [src/bin.rs:420] Capture finished
2023-01-16 01:34:32.643805 INFO [src/bin.rs:410] Playback finished