[PROBLEM] Airplay No Sound Output

[Tim Curtis]

So this issue came back, and restarting my devices has not been the solution.

I've been troubleshooting the issue with Mike at shairport-sync here: https://github.com/mikebrady/shairport-sync/issues/2031

In my debugging, it appears that the audio output is being blocked by something waiting on timedated service. When this service terminates, playback finally begins. 
@Tim Curtis Any guesses on what could be the culprit? I haven't been able to trace down which processes are responsible.

I changed the tag back to "Problem".

I'll give the Git issue a read and do some poking around to see if I can gain any insights into what might be happening.

[Tim Curtis]

Also, prolly a good idea to reboot one of the affected Pi's and post the startup log so we can see what the configuration looks like.

Code:

moodeutl -l

[Tim Curtis]

I'm not able to repro the "delayed audio" issue but below is some output from journalctl when my iPhone starts playback to moode AirPlay receiver. The audio is heard almost immediately after tapping the play button.

Note the ~30 sec time period between "Started" and "Deactivated"

Code:

Jun 18 17:41:51 moode9 dbus-daemon[565]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.635' (uid=0 pid=59454 comm="timedatectl")
Jun 18 17:41:51 moode9 systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Jun 18 17:41:51 moode9 dbus-daemon[565]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jun 18 17:41:51 moode9 systemd[1]: Started systemd-timedated.service - Time & Date Service.
Jun 18 17:42:24 moode9 systemd[1]: systemd-timedated.service: Deactivated successfully.

What does the Network section of moode.log look like in your configuration?

[christopherr]

Also, prolly a good idea to reboot one of the affected Pi's and post the startup log so we can see what the configuration looks like.

Code:

moodeutl -l

Code:

20250626 124723 worker: --                                                                                                                          
20250626 124723 worker: -- Start moOde 9 series                                                                                                    
20250626 124723 worker: --                                                                                                                          
20250626 124723 worker: Daemonize:     complete                                                                                                    
20250626 124729 worker: Linux startup: complete                                                                                                    
20250626 124729 worker: Boot config:   ok                                                                                                          
20250626 124729 worker: Boot folder:   ok                                                                                                          
20250626 124729 worker: Radio table:   ok                                                                                                          
20250626 124731 worker: PHP session:   cleaned                                                                                                      
20250626 124731 worker: PHP session:   loaded                                                                                                      
20250626 124735 worker: Package locks: applied                                                                                                      
20250626 124736 worker: File check:    complete                                                                                                    
20250626 124736 worker: Log to RAM:    on                                                                                                          
20250626 124736 worker: Tmp to RAM:    on                                                                                                          
20250626 124736 worker: Debug logging: off                                                                                                          
20250626 124736 worker: --                                                                                                                          
20250626 124736 worker: -- System                                                                                                                  
20250626 124736 worker: --                                                                                                                          
20250626 124738 worker: Host name:     kitchen                                                                                                      
20250626 124738 worker: RPi model:     Pi-3B+ 1.3 1GB                                                                                              
20250626 124738 worker: moOde release: 9.3.6 2025-06-06                                                                                            
20250626 124738 worker: RaspiOS:       12.11 Bookworm 64-bit                                                                                        
20250626 124738 worker: Linux Kernel:  6.12.25 64-bit                                                                                              
20250626 124738 worker: MPD version:   0.24.4                                                                                                      
20250626 124738 worker: User id:       christopherrudmin                                                                                            
20250626 124738 worker: Home folder:   /home/christopherrudmin                                                                                      
20250626 124738 worker: Time zone:     Canada/Eastern                                                                                              
20250626 124738 worker: Kbd layout:    us                                                                                                          
20250626 124738 worker: HDMI ports(s): on                                                                                                          
20250626 124738 worker: HDMI-CEC:      off                                                                                                          
20250626 124738 worker: Sys LED0:      off                                                                                                          
20250626 124738 worker: Sys LED1:      off                                                                                                          
20250626 124738 worker: Reduce power:  n/a                                                                                                          
20250626 124738 worker: Fan control:   n/a                                                                                                          
20250626 124738 worker: CPU governor:  ondemand                                                                                                    
20250626 124738 worker: --                                                                                                                          
20250626 124738 worker: -- Network                                                                                                                  
20250626 124738 worker: --                                                                                                                          
20250626 124738 worker: Eth0                                                                                                                        
20250626 124739 worker: Ethernet: adapter exists                                                                                                    
20250626 124739 worker: Ethernet: timeout off                                                                                                      
20250626 124739 worker: Ethernet: address not assigned                                                                                              
20250626 124739 worker: Wlan0                                                                                                                      
20250626 124739 worker: Wireless: adapter exists                                                                                                    
20250626 124739 worker: Wireless: country CA                                                                                                        
20250626 124739 worker: Wireless: SSID    carpenoctem                                                                                              
20250626 124739 worker: Wireless: other   None                                                                                                      
20250626 124739 worker: Wireless: timeout up to 90 secs                                                                                            
20250626 124739 worker: Wireless: connect to carpenoctem                                                                                            
20250626 124739 worker: Wireless: method  dhcp                                                                                                      
20250626 124739 worker: Wireless: address 192.168.219.8                                                                                            
20250626 124739 worker: Wireless: netmask 255.255.255.0                                                                                            
20250626 124739 worker: Wireless: gateway 192.168.219.1                                                                                            
20250626 124739 worker: Wireless: pri DNS 192.168.219.1                                                                                            
20250626 124739 worker: Wireless: domain  none found                                                                                                
20250626 124739 worker: mDNS                                                                                                                        
20250626 124739 worker: Discover: IPv4 and IPv6                                                                                                    
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: -- File sharing                                                                                                            
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: SMB file sharing:  off                                                                                                      
20250626 124739 worker: SMB password:      none                                                                                                    
20250626 124739 worker: NFS file sharing:  off                                                                                                      
20250626 124739 worker: DLNA file sharing: off                                                                                                      
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: -- Special configs                                                                                                          
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: Plexamp:          installed                                                                                                
20250626 124739 worker: RoonBridge:       installed                                                                                                
20250626 124739 worker: Allo Boss 2:      not detected, OLED script ok                                                                              
20250626 124739 worker: Allo Piano 2.1:   not detected                                                                                              
20250626 124739 worker: IQaudIO DigiAMP+: not detected                                                                                              
20250626 124739 worker: RPi DigiAMP+:     not detected                                                                                              
20250626 124739 worker: Bluetooth:        session vars ok                                                                                          
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: -- ALSA debug                                                                                                              
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: Cards:  0:DAC             1:vc4hdmi         2:empty           3:empty                                                      
20250626 124739 worker:         4:empty           5:empty           6:empty           7:empty                                                      
20250626 124739 worker: Mixers: 0:(PCM)           1:none            2:none            3:none                                                        
20250626 124739 worker:         4:none            5:none            6:none            7:none                                                        
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: -- Audio configuration                                                                                                      
20250626 124739 worker: --                                                                                                                          
20250626 124739 worker: Audio device:  0:USB Audio DAC                                                                                              
20250626 124740 worker: ALSA card:     has not been reassigned                                                                                      
20250626 124740 worker: MPD config:    update not needed                                                                                            
20250626 124740 worker: ALSA output:   USB                                                                                                          
20250626 124740 worker: ALSA mode:     Default (plughw)                                                                                            
20250626 124740 worker: ALSA mixer:    PCM                                                                                                          
20250626 124740 worker: ALSA volume:   0% (-128.00dB)                                                                                              
20250626 124740 worker: ALSA maxvol:   100%                                                                                                        
20250626 124740 worker: ALSA loopback: off                                                                                                          
20250626 124740 worker: MPD mixer      Hardware                                                                                                    
20250626 124740 worker: Integ audio:   vc4-kms-v3d                                                                                                  
20250626 124740 worker: Audio formats: S16_LE                                                                                                      
20250626 124740 worker: CamillaDSP:    off                                                                                                          
20250626 124740 worker: CDSP volume:   -120dB                                                                                                      
20250626 124740 worker: CDSP volrange: 60dB                                                                                                        
20250626 124740 worker: --                                                                                                                          
20250626 124740 worker: -- MPD startup                                                                                                              
20250626 124740 worker: --                                                                                                                          
20250626 124741 worker: MPD service:        started                                                                                                
20250626 124741 worker: MPD port 6600:      accepting connections                                                                                  
20250626 124741 worker: MPD ALSA Default:   on                                                                                                      
20250626 124741 worker: MPD ALSA Bluetooth: off                                                                                                    
20250626 124741 worker: MPD HTTP Server:    off                                                                                                    
20250626 124742 worker: MPD volume:         0                                                                                                      
20250626 124742 worker: Saved MPD vol:      -1                                                                                                      
20250626 124742 worker: Saved SRC vol:      0                                                                                                      
20250626 124742 worker: MPD crossfade:      off                                                                                                    
20250626 124742 worker: MPD ignore CUE:     no                                                                                                      
20250626 124742 worker: MPD CDSP volsync:   off                                                                                                    
20250626 124742 worker: --                                                                                                                          
20250626 124742 worker: -- Music sources                                                                                                            
20250626 124742 worker: --                                                                                                                          
20250626 124742 worker: USB drives:     none                                                                                                        
20250626 124742 worker: NVMe drives:    none                                                                                                        
20250626 124742 worker: SATA drives:    none                                                                                                        
20250626 124742 worker: NAS sources:    none                                                                                                        
20250626 124742 worker: --                                                                                                                          
20250626 124742 worker: -- Feature availability                                                                                                    
20250626 124742 worker: --                                                                                                                          
20250626 124742 worker: Input select:    available, Source: MPD, Output: USB Audio DAC                                                              
20250626 124742 worker: Bluetooth:       available, PIN: None, ALSA/CDSP maxvol: 100%/0dB, ALSA outmode: Standard                                  
20250626 124743 worker: AirPlay:         started                                                                                                    
20250626 124743 worker: Spotify Connect: available                                                                                                  
20250626 124743 worker: Deezer Connect:  n/a                                                                                                        
20250626 124743 worker: Squeezelite:     available                                                                                                  
20250626 124743 worker: UPnP client:     available                                                                                                  
20250626 124743 worker: Plexamp:         available, ALSA maxvol: 100%                                                                              
20250626 124743 worker: RoonBridge:      available                                                                                                  
20250626 124743 worker: Multiroom:       available                                                                                                  
20250626 124743 worker: GPIO buttons:    available                                                                                                  
20250626 124743 worker: Stream recorder: n/a                                                                                                        
20250626 124743 worker: --                                                                                                                          
20250626 124743 worker: -- Security                                                                                                                
20250626 124743 worker: --                                                                                                                          
20250626 124743 worker: Web SSH server:    on                                                                                                      
20250626 124743 worker: XSS detection:     off                                                                                                      
20250626 124743 worker: HTTPS mode:        available                                                                                                
20250626 124743 worker: --                                                                                                                          
20250626 124743 worker: -- Peripherals                                                                                                              
20250626 124743 worker: --                                                                                                                          
20250626 124757 worker: Local display:   off                                                                                                        
20250626 124757 worker: Chromium ver:    126.0.6478.164-rpt1                                                                                        
20250626 124757 worker: Chromium cfg:    directory ok                                                                                              
20250626 124757 worker: Screen blank     600                                                                                                        
20250626 124757 worker: On-screen kbd:   off                                                                                                        
20250626 124757 worker: Disable GPU:     off                                                                                                        
20250626 124757 worker: HDMI orient:     landscape                                                                                                  
20250626 124757 worker: HDMI 4K 60Hz:    off                                                                                                        
20250626 124757 worker: DSI scn type:    none                                                                                                      
20250626 124757 worker: DSI port:        1                                                                                                          
20250626 124757 worker: DSI brightness:  255                                                                                                        
20250626 124757 worker: DSI rotate:      0                                                                                                          
20250626 124757 worker: Rotary encoder:  off                                                                                                        
20250626 124757 worker: Triggerhappy:    off                                                                                                        
20250626 124757 worker: LCD updater:     off                                                                                                        
20250626 124757 worker: --                                                                                                                          
20250626 124757 worker: -- Miscellaneous                                                                                                            
20250626 124757 worker: --                                                                                                                          
20250626 124757 worker: Software update:   Automatic check off                                                                                      
20250626 124757 worker: Auto-CoverView:    off                                                                                                      
20250626 124757 worker: CoverView timeout: Never                                                                                                    
20250626 124757 worker: Auto-shuffle:      off                                                                                                      
20250626 124757 worker: Auto-play:         off                                                                                                      
20250626 124757 worker: Maintenance task:  360 mins                                                                                                
20250626 124757 worker: Current view:      reset to Playback                                                                                        
20250626 124757 worker: Active flags:      all false                                                                                                
20250626 124757 worker: Reset flags:       skipped                                                                                                  
20250626 124757 worker: Session check:     ok                                                                                                      
20250626 124757 worker: --                                                                                                                          
20250626 124757 worker: -- Startup complete                                                                                                        
20250626 124757 worker: --                                                                                                                          
20250626 124757 worker: Mount monitor:    started                                                                                                  
20250626 124757 worker: Radio monitor:    off                                                                                                      
20250626 124757 worker: Watchdog monitor: started                                                                                                  
20250626 124757 worker: Responsiveness:   Default                                                                                                  
20250626 124757 worker: Ready

[christopherr]

Here is a summary of key information which might be useful in debugging this:


- Occurs on both pi-3B+ and pi4 
- Only happens when airplaying from iphones (verified with 14Pro and 12mini) (airplaying from macbook and iPad does not exhibit this issue)
- Seems to be resolved when systemd-timedated.service terminates
- Network is dual stack ipv4 and ipv6 (may or may not be relevant)
- Pi is connected over wi-fi (i'm not able to run ethernet to its location)

Things I have tried without success:
- Turning off sonos which is on the network
- Disabling the run_this_before_entering_active_state and run_this_after_exiting_active_state scripts
- Disable ipv6 announcements for mDNS
- Disabling other renderers I have installed (plexamp and roonbridge)


I could do a packet capture on the Pi and on my iPhone, but I don't have the knowledge to be able to say if what is happening is probelematic or not.

[Tim Curtis]

Here is a summary of key information which might be useful in debugging this:


- Occurs on both pi-3B+ and pi4 
- Only happens when airplaying from iphones (verified with 14Pro and 12mini) (airplaying from macbook and iPad does not exhibit this issue)
- Seems to be resolved when systemd-timedated.service terminates
- Network is dual stack ipv4 and ipv6 (may or may not be relevant)
- Pi is connected over wi-fi (i'm not able to run ethernet to its location)

Things I have tried without success:
- Turning off sonos which is on the network
- Disabling the run_this_before_entering_active_state and run_this_after_exiting_active_state scripts
- Disable ipv6 announcements for mDNS
- Disabling other renderers I have installed (plexamp and roonbridge)


I could do a packet capture on the Pi and on my iPhone, but I don't have the knowledge to be able to say if what is happening is probelematic or not.

I don't see anything suspicious in the moode log.

In the absence of a "smoking gun" and since the issue only occurs on your iPhone (other clients are ok) I would try to rule out a network issue for example the iPhone should be on the same IPv4 subnet 192.168.219.x as the Pi and connected to the same WiFi access point as the other clients that don't exhibit the issue.

If a network issue can be ruled out then if possible try someone else iPhone and see if the 30 sec play delay occurs.

[christopherr]

Yeah, all devices are on the same subnet.  There is a firewall on the WAN, but no firewalls on the internal LAN.
I have disabled the IP anonymisation features on the iphones (limit IP Address tracking and Private WiFi mac addresses) with no success.