Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

[Problem]: Shairport in Airplay 2 mode doesn't work for iOS 18.1.1 but does for OSX 14 and iOS 16 #1942

Closed
1 of 2 tasks
genekellyjr opened this issue Dec 11, 2024 · 6 comments
Closed
1 of 2 tasks

[Problem]: Shairport in Airplay 2 mode doesn't work for iOS 18.1.1 but does for OSX 14 and iOS 16 #1942

genekellyjr opened this issue Dec 11, 2024 · 6 comments
Labels
troubleshooting

Comments

@genekellyjr
Copy link

genekellyjr commented Dec 11, 2024 •
edited by mikebrady
Loading

What happened?

Absolutely love this program, thank you so much for making and sharing it!

I've found that the iPhone in my household with iOS 18.1.1 can't use Shairport Sync in AirPlay 2 mode but an OSX 14 (Sonoma) Mac and iOS 16 work successfully.

The iOS 18.1.1 phone thinks it is playing, but no audio happens. Works fine for iOS 16 and it used to work fine on that iPhone prior to the latest iOS (not sure if it started with 18.1.1 or happened a tad earlier).

The CPU load is different, so Shairport Sync may know it's not getting anything of value. When audio works CPU load is at 16% (of the 1 core) and with the iOS 18.1.1 iPhone it's 5%.

Attached is a log_verbosity = 2; of trying to play a song and stopping.

Relevant log output

`journalctl -u shairport-sync.service -e --no-pager` 
Dec 10 20:29:21 tunespi shairport-sync[1631]:         31.746319000 "rtsp.c:5691" Connection 5: New connection from fe80::1475:1277:2824:ab06:54182 to self at fe80::ddd3:3278:cd62:998a:7000.
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.189131000 "rtsp.c:2256" Connection 5: handle_pair-setup Content-Length 9
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.215332000 "rtsp.c:2256" Connection 5: handle_pair-setup Content-Length 457
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.342342000 "rtsp.c:2877" Connection 5: SETUP (AirPlay 2)
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.001623000 "rtsp.c:2897" Connection 5 SETUP: No "streams" array has been found -- create an event thread and open a TCP port.
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.000191000 "rtsp.c:2910" Connection 5: AP2 PTP connection from fe80::1475:1277:2824:ab06:54182 ("Auriga") to self at fe80::ddd3:3278:cd62:998a:7000.
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.000099000 "rtsp.c:586" Connection 5: request to acquire principal_conn.
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.000979000 "ptp-utilities.c:165" ptp_shm_interface_open with mapped_addr = 3069906944
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.000125000 "ptp-utilities.c:200" ptp_shm_interface_open -- already open!
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.000110000 "rtsp.c:2998" Updated groupContainsGroupLeader to 0
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.000677000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp T fe80::1475:1277:2824:ab06"
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.004561000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp B"
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.003007000 "rtsp.c:3108" Connection 5: TCP PTP event port opened: 33869.
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.001505000 "rtsp.c:3135" Connection 5: SETUP mdns_update on PTP stream.
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.006078000 "rtp.c:1572" Connection 5: AP2 Event Receiver started
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.171490000 "rtp.c:1584" Connection 5: rtp_event_receiver accepted a connection on socket 11 and moved to a new socket 12.
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.010721000 "rtsp.c:4402" Connection 5: current volume (-12.206203) requested
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.005794000 "rtsp.c:1582" Connection 5: RECORD on PTP stream
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.015357000 "rtsp.c:2476" Connection 5: POST /command Content-Length 128
Dec 10 20:29:22 tunespi shairport-sync[1631]:          0.015973000 "rtsp.c:2476" Connection 5: POST /command Content-Length 6341
Dec 10 20:29:24 tunespi shairport-sync[1631]:          1.670448000 "rtsp.c:2877" Connection 5: SETUP (AirPlay 2)
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.002335000 "rtsp.c:3220" Connection 5: SETUP on PTP stream. A "streams" array has been found
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000233000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp B"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.007291000 "rtsp.c:3248" Connection 5: UDP control port opened: 43284.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000577000 "rtsp.c:3267" Connection 5: SETUP AP2 no Active-Remote information  the SETUP Record.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000185000 "rtsp.c:3286" Connection 5: SETUP AP2 doesn't include DACP-ID string information.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000273000 "rtsp.c:3354" Connection 5. AP2 Buffered Audio Stream.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000141000 "rtsp.c:3355" Buffered Audio Stream SETUP incoming message
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000116000 "rtsp.c:3355"   Type: "Content-Length", content: "296"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000119000 "rtsp.c:3355"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000114000 "rtsp.c:3355"   Type: "CSeq", content: "13"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000110000 "rtsp.c:3355"   Type: "User-Agent", content: "AirPlay/830.10.1"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000468000 "rtsp.c:3355"   Content Plist (as XML):
                                              --
                                              <?xml version="1.0" encoding="UTF-8"?>
                                              <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
                                              <plist version="1.0">
                                              <dict>
                                                      <key>streams</key>
                                                      <array>
                                                              <dict>
                                                                      <key>type</key>
                                                                      <integer>103</integer>
                                                                      <key>audioMode</key>
                                                                      <string>default</string>
                                                                      <key>ct</key>
                                                                      <integer>4</integer>
                                                                      <key>streamConnectionID</key>
                                                                      <integer>492692723891522027</integer>
                                                                      <key>spf</key>
                                                                      <integer>1024</integer>
                                                                      <key>shk</key>
                                                                      <data>
                                                                      kXV+y0SJtwbU0rzFAI/rWLEJGQNdFV95NvbLHneSYcM=
                                                                      </data>
                                                                      <key>supportsDynamicStreamID</key>
                                                                      <true/>
                                                                      <key>audioFormat</key>
                                                                      <integer>4194304</integer>
                                                                      <key>audioFormatIndex</key>
                                                                      <integer>22</integer>
                                                                      <key>clientID</key>
                                                                      <string>com.apple.Music</string>
                                                              </dict>
                                                      </array>
                                              </dict>
                                              </plist>
                                              --
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000326000 "rtsp.c:3372" Connection 5: TCP Buffered Audio port opened: 40191.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000163000 "activity_monitor.c:64" abeg
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.001118000 "activity_monitor.c:167" am_state: am_active
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.014484000 "audio_alsa.c:608" alsa: output format chosen is "S32".
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000778000 "audio_alsa.c:651" alsa: output speed chosen is 44100.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.001647000 "audio_alsa.c:1980" alsa: prepare() -- opened output device
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.006349000 "player.c:3632" Connection 5: player_play.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.010290000 "rtp.c:2343" 32-bit output format chosen
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000351000 "rtp.c:1425" Connection 5: No NQPTP master clock.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.029215000 "player.c:2259" Set initial volume to -12.206203.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000532000 "player.c:3515" Software attenuation set to -2081.872180, i.e 5964.085224 out of 65,536, for airplay volume of -12.206203
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000154000 "loudness.c:47" Volume: -20.8 dB - Loudness gain @10Hz: 0.4 dB
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.003952000 "player.c:3538" player_volume_without_notification: volume mode is 0, airplay volume is -12.21, software_attenuation dB: -20.82, hardware_attenuation dB: 0.00, muting is disabled.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000247000 "player.c:2262" Play begin
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.039645000 "rtsp.c:2555" Connection 5: POST /audioMode Content-Length 64
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000188000 "rtsp.c:2557"   Type: "Content-Length", content: "64"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000088000 "rtsp.c:2557"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000080000 "rtsp.c:2557"   Type: "CSeq", content: "14"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000076000 "rtsp.c:2557"   Type: "User-Agent", content: "AirPlay/830.10.1"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000145000 "rtsp.c:2557"   Content Plist (as XML):
                                              --
                                              <?xml version="1.0" encoding="UTF-8"?>
                                              <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
                                              <plist version="1.0">
                                              <dict>
                                                      <key>audioMode</key>
                                                      <string>default</string>
                                              </dict>
                                              </plist>
                                              --
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.015044000 "rtsp.c:2555" Connection 5: POST /audioMode Content-Length 64
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000187000 "rtsp.c:2557"   Type: "Content-Length", content: "64"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000091000 "rtsp.c:2557"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000087000 "rtsp.c:2557"   Type: "CSeq", content: "15"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000077000 "rtsp.c:2557"   Type: "User-Agent", content: "AirPlay/830.10.1"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000151000 "rtsp.c:2557"   Content Plist (as XML):
                                              --
                                              <?xml version="1.0" encoding="UTF-8"?>
                                              <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
                                              <plist version="1.0">
                                              <dict>
                                                      <key>audioMode</key>
                                                      <string>default</string>
                                              </dict>
                                              </plist>
                                              --
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.076922000 "rtsp.c:1967" networkTimeTimelineID "5409106457810008".
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000215000 "rtsp.c:1972" anchor networkTimeSecs is 788550.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000100000 "rtsp.c:1977" anchor networkTimeFrac is 06996859251146620928.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000088000 "rtsp.c:1988" anchorTimeNanoseconds looks like 788550379300500.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000093000 "rtp.c:1274" set_ptp_anchor_info: clock: 5409106457810008, rtptime: 4103856092, networktime: 2cd2eb13ee694.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.003119000 "rtp.c:1277" Connection 5: Set Anchor Clock: 5409106457810008.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000217000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp B"
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.002942000 "rtsp.c:2015" Connection 5: Start playing, with anchor clock 5409106457810008.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.020603000 "rtsp.c:3626" Connection 5: request to set AirPlay Volume to: -12.206203.
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000212000 "player.c:3515" Software attenuation set to -2081.872180, i.e 5964.085224 out of 65,536, for airplay volume of -12.206203
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.000121000 "loudness.c:47" Volume: -20.8 dB - Loudness gain @10Hz: 0.4 dB
Dec 10 20:29:24 tunespi shairport-sync[1631]:          0.001706000 "player.c:3538" player_volume_without_notification: volume mode is 0, airplay volume is -12.21, software_attenuation dB: -20.82, hardware_attenuation dB: 0.00, muting is disabled.
Dec 10 20:29:25 tunespi shairport-sync[1631]:          0.586031000 "rtsp.c:2476" Connection 5: POST /command Content-Length 6341
Dec 10 20:30:21 tunespi shairport-sync[1631]:         56.581820000 "rtsp.c:673" 2 active connections.
Dec 10 20:30:26 tunespi shairport-sync[1631]:          4.492409000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp P"
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.007789000 "rtsp.c:2025" Connection 5: Pause playing.
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000248000 "rtp.c:1322" Connection 5: Clear anchor information.
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000164000 "rtsp.c:2033" Connection 5: Stop the output backend.
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000119000 "audio_alsa.c:1883" alsa: do_close()
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.010319000 "audio_alsa.c:1896" alsa: do_close() -- closing alsa handle
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.593910000 "rtsp.c:1838" FLUSHBUFFERED request
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000293000 "rtsp.c:1838"   Type: "Content-Length", content: "87"
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000132000 "rtsp.c:1838"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000121000 "rtsp.c:1838"   Type: "CSeq", content: "56"
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.002474000 "rtsp.c:1838"   Type: "User-Agent", content: "AirPlay/830.10.1"
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000332000 "rtsp.c:1838"   Content Plist (as XML):
                                              --
                                              <?xml version="1.0" encoding="UTF-8"?>
                                              <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
                                              <plist version="1.0">
                                              <dict>
                                                      <key>flushUntilSeq</key>
                                                      <integer>2068805</integer>
                                                      <key>flushUntilTS</key>
                                                      <integer>4112106931</integer>
                                              </dict>
                                              </plist>
                                              --
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000197000 "rtsp.c:1849" Can't find a flushFromSeq
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000134000 "rtsp.c:1861" Can't find a flushFromTS
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000120000 "rtsp.c:1874" flushUntilSeq is 2068805.
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.001338000 "rtsp.c:1882" flushUntilTS is 4112106931.
Dec 10 20:30:26 tunespi shairport-sync[1631]:          0.000209000 "rtsp.c:1929" Immediate Flush Requested
Dec 10 20:30:32 tunespi shairport-sync[1631]:          5.752108000 "rtsp.c:2746" Connection 5: TEARDOWN 2 PTP stream.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000244000 "rtsp.c:2748" TEARDOWN:
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000128000 "rtsp.c:2748"   Type: "Content-Length", content: "84"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000115000 "rtsp.c:2748"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000117000 "rtsp.c:2748"   Type: "CSeq", content: "60"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.002809000 "rtsp.c:2748"   Type: "User-Agent", content: "AirPlay/830.10.1"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000342000 "rtsp.c:2748"   Content Plist (as XML):
                                              --
                                              <?xml version="1.0" encoding="UTF-8"?>
                                              <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
                                              <plist version="1.0">
                                              <dict>
                                                      <key>streams</key>
                                                      <array>
                                                              <dict>
                                                                      <key>streamID</key>
                                                                      <integer>0</integer>
                                                                      <key>type</key>
                                                                      <integer>103</integer>
                                                              </dict>
                                                      </array>
                                              </dict>
                                              </plist>
                                              --
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000217000 "rtsp.c:2757" Connection 5: TEARDOWN PTP stream -- close the stream.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000130000 "player.c:3655" Connection 5: player_stop.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.001046000 "player.c:1827" Cancelling AP2 timing, control and audio threads...
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000201000 "player.c:1836" Connection 5: Delete Buffered Audio Stream thread
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.003449000 "rtp.c:2010" Buffered TCP Reader Thread Exit via Cleanup.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.004465000 "rtp.c:2183" Buffered Audio Receiver Cleanup Start.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.006482000 "rtp.c:2186" Connection 5: TCP Buffered Audio port closed: 40191.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000219000 "rtp.c:2189" Buffered Audio Receiver Cleanup Done.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.001720000 "player.c:1844" Connection 5: Delete AirPlay 2 Control thread
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000599000 "rtp.c:1633" Connection 5: AP2 Control Receiver Cleanup.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000287000 "rtp.c:1635" Connection 5: UDP control port 43284 closed.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.001274000 "rtp.c:1322" Connection 5: Clear anchor information.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.001924000 "player.c:432" 3072 buffers allocated, 3072 buffers released.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000189000 "player.c:1897" Connection 5: player terminated.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.001823000 "player.c:3670" Connection 5: player_stop successful.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000533000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp E"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.005013000 "rtsp.c:2762" Connection 5: TEARDOWN PTP stream -- close the stream complete
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.012752000 "rtsp.c:2746" Connection 5: TEARDOWN 2 PTP stream.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000220000 "rtsp.c:2748" TEARDOWN:
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000120000 "rtsp.c:2748"   Type: "Content-Length", content: "42"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000115000 "rtsp.c:2748"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000113000 "rtsp.c:2748"   Type: "CSeq", content: "61"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000120000 "rtsp.c:2748"   Type: "User-Agent", content: "AirPlay/830.10.1"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000177000 "rtsp.c:2748"   Content Plist (as XML):
                                              --
                                              <?xml version="1.0" encoding="UTF-8"?>
                                              <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
                                              <plist version="1.0">
                                              <dict/>
                                              </plist>
                                              --
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000158000 "rtsp.c:2765" Connection 5: TEARDOWN PTP stream -- close the connection.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000127000 "rtsp.c:2672" Connection 5: TEARDOWN PTP stream connection.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000121000 "rtsp.c:2692" Connection 5: TEARDOWN PTP stream Delete Event Thread.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.001917000 "rtp.c:1564" Connection 5: AP2 Event Receiver Cleanup.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000878000 "rtsp.c:2700" Connection 5: TEARDOWN PTP stream Close Event Socket.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.001302000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp T"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.008582000 "rtsp.c:2732" Connection 5: TEARDOWN mdns_update on PTP stream.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.022689000 "rtsp.c:2739" Connection 5: TEARDOWN PTP stream -- close the connection complete
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.009978000 "rtsp.c:1196" Connection 5: read result 0, error 11: "Resource temporarily unavailable".
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000168000 "rtsp.c:1298" Connection 5: Connection closed by client.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000106000 "rtsp.c:2672" Connection 5: TEARDOWN PTP stream connection.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000220000 "ptp-utilities.c:225" Send control message to NQPTP: "/nqptp T"
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.004365000 "rtsp.c:2739" Connection 5: TEARDOWN PTP stream -- close the connection complete
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000247000 "rtsp.c:5114" Connection 5: terminating -- closing RTSP connection socket 10: from fe80::1475:1277:2824:ab06:54182 to self at fe80::ddd3:3278:cd62:998a:7000.
Dec 10 20:30:32 tunespi shairport-sync[1631]:          0.000462000 "rtsp.c:5391" Connection 5: RTSP thread exit.
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.183130000 "rtsp.c:652" Found RTSP connection thread 5 in a non-running state.
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.000333000 "rtsp.c:655" Connection 5: deleted.
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.000112000 "rtsp.c:671" One active connection.
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.000245000 "rtsp.c:5691" Connection 6: New connection from fe80::1475:1277:2824:ab06:54184 to self at fe80::ddd3:3278:cd62:998a:7000.
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.237149000 "rtsp.c:2256" Connection 6: handle_pair-setup Content-Length 9
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.234723000 "rtsp.c:2256" Connection 6: handle_pair-setup Content-Length 457
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.322201000 "rtsp.c:2877" Connection 6: SETUP (AirPlay 2)
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.000350000 "rtsp.c:2897" Connection 6 SETUP: No "streams" array has been found -- create an event thread and open a TCP port.
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.000114000 "rtsp.c:2933" Connection 6: Remote Control connection from fe80::1475:1277:2824:ab06:54184 ("Auriga") to self at fe80::ddd3:3278:cd62:998a:7000.
Dec 10 20:30:33 tunespi shairport-sync[1631]:          0.021542000 "rtsp.c:1582" Connection 6: RECORD on Remote Control stream
Dec 10 20:30:36 tunespi shairport-sync[1631]:          2.540691000 "activity_monitor.c:84" aend
Dec 10 20:30:36 tunespi shairport-sync[1631]:          0.001637000 "activity_monitor.c:163" am_state: am_inactive

System Information.

RPi Zero, Raspbian 12 Bookworm-based, adafruit DAC that seems to crib hifiberry code. Built with '-O3 -g'. No issues on this lil potato, amazing work!

Configuration Information.

>> Display Config Start.

From "uname -a":
 Linux tunespi 6.6.51+rpt-rpi-v6 #1 Raspbian 1:6.6.51-1+rpt3 (2024-10-08) armv6l GNU/Linux

From /etc/os-release:
 Raspbian GNU/Linux 12 (bookworm)

From /sys/firmware/devicetree/base/model:
 Raspberry Pi Zero W Rev 1.1

Shairport Sync Version String:
 4.3.5-AirPlay2-smi10-alac-OpenSSL-Avahi-ALSA-soxr-convolution-metadata-sysconfdir:/etc

Command Line:
 shairport-sync --displayConfig

Configuration File:
 /etc/shairport-sync.conf

Configuration File Settings:
 general :
 {
   name = "Bookcase";
   interpolation = "soxr";
   output_backend = "alsa";
   mdns_backend = "avahi";
   alac_decoder = "apple";
   audio_backend_buffer_desired_length_in_seconds = 0.45;
 };

>> Display Config End.
>> Goodbye!

PulseAudio or PipeWire installed?

  • Check if your system uses a Sound Server.

How did you install Shairport Sync?

Built from source

Check previous issues

  • Confirm
@JayGatsby7
Copy link

JayGatsby7 commented Dec 11, 2024 •
edited by mikebrady
Loading

Seems to work for me but I’m using Raspberry Pi 3’s and using the Docker version of Shairport Sync. The one that was just updated a few days ago.

@mikebrady
Copy link
Owner

mikebrady commented Dec 11, 2024 •
edited
Loading

Thanks for the post. It works for me on iOS 18.1.1 on a variety of platforms, just as @JayGatsby7 has indicated. What might be helpful is to use it with a log verbosity of 1 but with statistics enabled. If you could run it for a few, maybe 10, minutes to (hopefully) see some statistics in the log, it might be interesting.

@genekellyjr
Copy link
Author

Thanks for responding so quickly! I suspected it worked for most everyone else. Maybe an edge case somehow?

Here's log lvl 1 + stats for iOS 18.1.1:

Dec 11 18:16:44 tunespi shairport-sync[645]:        357.317754000 "rtsp.c:2910" Connection 5: AP2 PTP connection from fe80::1475:1277:2824:ab06:54353 ("Auriga") to self at fe80::ddd3:3278:cd62:998a:7000.
Dec 11 18:16:47 tunespi shairport-sync[645]:          3.118671000 "rtsp.c:3267" Connection 5: SETUP AP2 no Active-Remote information  the SETUP Record.
Dec 11 18:16:47 tunespi shairport-sync[645]:          0.000442000 "rtsp.c:3286" Connection 5: SETUP AP2 doesn't include DACP-ID string information.
Dec 11 18:21:55 tunespi shairport-sync[645]:        307.378218000 "player.c:1807" Connection 5: Playback stopped. Total playing time 00:27:25.

And here's log lvl 1 + stats for iOS 16:

Dec 11 18:08:24 tunespi shairport-sync[645]:        269.878361000 "rtsp.c:2910" Connection 3: AP2 PTP connection from fe80::89b:9d14:bf0e:724e:50137 ("Capella") to self at fe80::ddd3:3278:cd62:998a:7000.
Dec 11 18:08:49 tunespi shairport-sync[645]:         25.810600000 "audio_alsa.c:1823" alsa: recovering from a previous underrun.
Dec 11 18:08:49 tunespi shairport-sync[645]:          0.032428000 "player.c:1256" Gone past starting time for 641971624 by 4428820 nanoseconds.
Dec 11 18:08:49 tunespi shairport-sync[645]:          0.007381000 "player.c:2837" Connection 3: Playback started at frame 641975144 -- AirPlay 2 Buffered.
Dec 11 18:08:57 tunespi shairport-sync[645]:          7.832104000 "player.c:2656" Sync Error ms | Net Sync PPM | All Sync PPM | Min DAC Queue | Min Buffers | Min Buffer Size | Output FPS (r) | Output FPS (c)
Dec 11 18:08:57 tunespi shairport-sync[645]:          0.007496000 "player.c:2656"         -0.20            2.9
  2.9            2936            25              164k              N/A              N/A
Dec 11 18:09:05 tunespi shairport-sync[645]:          7.980233000 "player.c:2656"         -0.66            8.5
  8.5            4163            41             1986k         44099.99         44099.90
Dec 11 18:09:13 tunespi shairport-sync[645]:          8.009812000 "player.c:2656"          0.37            2.8
  2.8            6292            44             3734k         44099.85         44099.77
Dec 11 18:09:21 tunespi shairport-sync[645]:          8.033164000 "player.c:2656"          0.10            5.7
  5.7            6301            44             3648k         44100.04         44099.95
Dec 11 18:09:29 tunespi shairport-sync[645]:          7.988422000 "player.c:2656"         -0.29            2.8
  2.8            5551            44             3649k         44099.99         44099.90
Dec 11 18:09:37 tunespi shairport-sync[645]:          7.988404000 "player.c:2656"         -0.04            2.8
  2.8            6456            45             3640k         44099.92         44099.84
Dec 11 18:09:45 tunespi shairport-sync[645]:          8.033504000 "player.c:2656"          0.63            0.0
  0.0            4616            40             3633k         44099.99         44099.90
Dec 11 18:09:53 tunespi shairport-sync[645]:          7.988402000 "player.c:2656"          0.48            0.0
  0.0            6464            44             3629k         44099.87         44099.78
Dec 11 18:10:01 tunespi shairport-sync[645]:          8.010018000 "player.c:2656"          0.02            8.5
  8.5            4626            43             3645k         44099.86         44099.77
Dec 11 18:10:09 tunespi shairport-sync[645]:          8.012151000 "player.c:2656"         -0.15            2.8
  2.8            6452            45             3668k         44099.87         44099.79
Dec 11 18:10:17 tunespi shairport-sync[645]:          7.987937000 "player.c:2656"         -0.19            5.7
  5.7            6054            44             3612k         44099.94         44099.85
Dec 11 18:10:25 tunespi shairport-sync[645]:          8.010061000 "player.c:2656"         -0.54            8.5
  8.5            5001            39             3537k         44099.90         44099.81
Dec 11 18:10:33 tunespi shairport-sync[645]:          8.012520000 "player.c:2656"         -0.18            5.7
  5.7            6234            44             3482k         44099.91         44099.82
Dec 11 18:10:41 tunespi shairport-sync[645]:          8.007923000 "player.c:2656"         -0.66            0.0
  0.0            6259            45             3417k         44099.93         44099.84
Dec 11 18:10:47 tunespi shairport-sync[645]:          5.653508000 "player.c:1800" Connection 3: Playback stopped. Total playing time 00:01:57. Output: 44099.93 (raw), 44099.84 (corrected) frames per second.

I included the iOS 16 since the 18.1.1 didn't include any actual statistics. And seemed to undercount the play time. CPU usage was very low during "playback".

@genekellyjr
Copy link
Author

genekellyjr commented Dec 12, 2024 •
edited by mikebrady
Loading

Well, I went to a friend's house and tried their real AirPlay 2 speaker and it didn't work either. So I did what I clearly should've done before - force restarted the phone - and lo and behold it worked there and on the Shairport Sync speaker!

So apologies for assuming Shairport Sync was at fault, it was Apple all along! Thank you again for your time!

@mikebrady
Copy link
Owner

Glad you got it working!

@mikebrady
Copy link
Owner

(I did a little editing of the messages to make it a bit clearer to users who comes across it in future -- just capital letters and a few typos. I hope that's okay with you.)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
troubleshooting
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@mikebrady @JayGatsby7 @genekellyjr