search

mikebrady / shairport-sync

AirPlay and AirPlay 2 audio player
Other
7.24k stars 573 forks source link

random disconnects with airplay 2 #1510

Closed Goldsucher closed 1 year ago

Goldsucher commented 2 years ago

Hi,

I have some random disconnects (sometimes every 5 minutes, sometimes every hour - very various).

Here is my log (with disconnect at 13:57), maybe someone can see the problem:

Aug 10 13:46:29 raspberrypi shairport-sync[12116]:          0.000184115 "player.c:2628" final sync adjustment: 10 silent frames added with a bias of 8 frames.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:        638.071005433 "rtsp.c:2568" TEARDOWN:
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.003959844 "rtsp.c:2568"   Type: "Content-Length", content: "200"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000218125 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000101979 "rtsp.c:2568"   Type: "CSeq", content: "556"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000089063 "rtsp.c:2568"   Type: "DACP-ID", content: "B7CB7DD4085EC234"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000088021 "rtsp.c:2568"   Type: "Active-Remote", content: "2020748185"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000079948 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000442708 "rtsp.c:2568"   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>ccCountSender</key>
                                                                           <integer>0</integer>
                                                                           <key>ccCountNonAPSender</key>
                                                                           <integer>0</integer>
                                                                           <key>ioDiscontinuityCount</key>
                                                                           <integer>0</integer>
                                                                           <key>primaryPacketDropCount</key>
                                                                           <integer>0</integer>
                                                                           <key>streamID</key>
                                                                           <integer>0</integer>
                                                                           <key>type</key>
                                                                           <integer>96</integer>
                                                                           <key>ccCountAPSender</key>
                                                                           <integer>0</integer>
                                                                   </dict>
                                                           </array>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000300885 "rtsp.c:2580" Connection 1: TEARDOWN a PTP stream.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000944063 "audio_alsa.c:1829" alsa: do_close()
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.005626875 "audio_alsa.c:1841" alsa: do_close() -- closing alsa handle
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.011596667 "player.c:1670" Cancelling AP2 timing, control and audio threads...
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000272031 "player.c:1673" Connection 1: Delete Realtime Audio Stream thread
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000500469 "rtp.c:1863" Realtime Audio Receiver Cleanup Start.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000891302 "rtp.c:1866" Connection 51609: closing realtime audio port 439008
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000165729 "rtp.c:1868" Realtime Audio Receiver Cleanup Done.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000739896 "player.c:1687" Connection 1: Delete AirPlay 2 Control thread
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000464948 "rtp.c:1632" Connection 1: AP2 Control Receiver Cleanup.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000235364 "rtp.c:1635" Connection 1: UDP control port 35873 closed.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000436771 "rtp.c:1325" Connection 1: Clear anchor information.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001368906 "player.c:1742" Connection 1: player terminated.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001056511 "rtsp.c:2584" Connection 1: TEARDOWN phase one complete
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.005808854 "rtsp.c:2568" TEARDOWN:
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000184948 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000094114 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000080938 "rtsp.c:2568"   Type: "CSeq", content: "557"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000078802 "rtsp.c:2568"   Type: "DACP-ID", content: "B7CB7DD4085EC234"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000075469 "rtsp.c:2568"   Type: "Active-Remote", content: "2020748185"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000078645 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000174011 "rtsp.c:2568"   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>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000130625 "rtsp.c:2511" Connection 1: TEARDOWN a PTP stream connection.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000095937 "rtsp.c:2528" Connection 1: TEARDOWN Delete Event Thread.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000690834 "rtp.c:1563" Connection 1: AP2 Event Receiver Cleanup.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000472552 "rtsp.c:2534" Connection 1: TEARDOWN Close Event Socket.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001621094 "rtsp.c:2555" Connection 1: TEARDOWN mdns_update on PTP stream.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.005232968 "rtsp.c:529" Connection 1: release play lock.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000195261 "rtsp.c:533" Connection 1: release play lock.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000113802 "rtsp.c:2588" Connection 1: TEARDOWN phase two complete
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.014223802 "rtsp.c:4782" Connection 1: rtsp_conversation_thread_func_cleanup_function called.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000213958 "rtsp.c:2511" Connection 1: TEARDOWN a PTP stream connection.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000087136 "rtsp.c:2534" Connection 1: TEARDOWN Close Event Socket.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001137864 "rtsp.c:2555" Connection 1: TEARDOWN mdns_update on PTP stream.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.004171094 "rtsp.c:529" Connection 1: release play lock.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000621979 "rtsp.c:4814" Connection 1: terminating connection from 2a02:810d:43c0:1bc:b880:fcc1:8c4:e336:57064 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001041458 "rtsp.c:4871" Connection 1: Closed.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.029842344 "rtsp.c:730" Connection 1: deleted in cleanup.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000415521 "rtsp.c:5319" Connection 2: new connection from 2a02:810d:43c0:1bc:b880:fcc1:8c4:e336:61388 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000765833 "rtsp.c:1903" Connection 2: GET /info :: Content-Length 70
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000145573 "rtsp.c:1904" GET request
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000072292 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000080885 "rtsp.c:1904"   Type: "Content-Length", content: "70"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000074167 "rtsp.c:1904"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000072656 "rtsp.c:1904"   Type: "CSeq", content: "0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000064323 "rtsp.c:1904"   Type: "DACP-ID", content: "1BC597D7A0F4FAA7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000069896 "rtsp.c:1904"   Type: "Active-Remote", content: "4229745777"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000076510 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000179896 "rtsp.c:1904"   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>qualifier</key>
                                                           <array>
                                                                   <string>txtAirPlay</string>
                                                           </array>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000099375 "rtsp.c:1563" GET /info:
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000073490 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000072292 "rtsp.c:1563"   Type: "Content-Length", content: "70"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000197760 "rtsp.c:1563"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000087135 "rtsp.c:1563"   Type: "CSeq", content: "0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000069063 "rtsp.c:1563"   Type: "DACP-ID", content: "1BC597D7A0F4FAA7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000064792 "rtsp.c:1563"   Type: "Active-Remote", content: "4229745777"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000065989 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000145625 "rtsp.c:1563"   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>qualifier</key>
                                                           <array>
                                                                   <string>txtAirPlay</string>
                                                           </array>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000087708 "rtsp.c:1573" Connection 2: GET_INFO: Source AirPlay Version is: 620.8.2.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000110469 "rtsp.c:1595" GET /info Stage 1: qualifier: txtAirPlay
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.002178438 "rtsp.c:1677" GET /info Stage 1 Response:
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000208489 "rtsp.c:1677"   Response Code: 501.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000118125 "rtsp.c:1677"   Type: "CSeq", content: "0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000117448 "rtsp.c:1677"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000121198 "rtsp.c:1677"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001174063 "rtsp.c:1677"   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>audioLatencies</key>
                                                           <array>
                                                                   <dict>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>100</integer>
                                                                   </dict>
                                                                   <dict>
                                                                           <key>audioType</key>
                                                                           <string>default</string>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>100</integer>
                                                                   </dict>
                                                                   <dict>
                                                                           <key>audioType</key>
                                                                           <string>media</string>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>100</integer>
                                                                   </dict>
                                                                   <dict>
                                                                           <key>audioType</key>
                                                                           <string>media</string>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>102</integer>
                                                                   </dict>
                                                           </array>
                                                           <key>keepAliveLowPower</key>
                                                           <true/>
                                                           <key>keepAliveSendStatsAsBody</key>
                                                           <true/>
                                                           <key>manufacturer</key>
                                                           <string>Shairport Sync</string>
                                                           <key>nameIsFactoryDefault</key>
                                                           <false/>
                                                           <key>protocolVersion</key>
                                                           <string>1.1</string>
                                                           <key>sdk</key>
                                                           <string>AirPlay;2.0.2</string>
                                                           <key>sourceVersion</key>
                                                           <string>366.0</string>
                                                           <key>statusFlags</key>
                                                           <integer>4</integer>
                                                           <key>txtAirPlay</key>
                                                           <data>
                                                           BWFjbD0wGmRldmljZWlkPWI4OjI3OmViOjhkOmRhOjAwG2ZlYXR1cmVzPTB4NDA1QzRB
                                                           MDAsMHgxQzM0MAdyc2Y9MHgwCWZsYWdzPTB4NBRtb2RlbD1TaGFpcnBvcnQgU3luYw1t
                                                           YW51ZmFjdHVyZXI9DXNlcmlhbE51bWJlcj0NcHJvdG92ZXJzPTEuMQ1zcmN2ZXJzPTM2
                                                           Ni4wJ3BpPTQ1NjhmMWUzLTQ2OWYtNGQ4Yy1hMTdhLWM3ODJjYzRmNjI5YihnaWQ9NDU2
                                                           OGYxZTMtNDY5Zi00ZDhjLWExN2EtYzc4MmNjNGY2MjliBmdjZ2w9MENwaz05NzMzMzk5
                                                           NjRhZTIzNzA3OTlhMmFmZDg4MjQwNDk5MThlOGQ3OWM1Y2YwNGVjNTQ2NmJhYWM0MTU0
                                                           NjhiYjQz
                                                           </data>
                                                           <key>features</key>
                                                           <integer>496155701824000</integer>
                                                           <key>deviceID</key>
                                                           <string>b8:27:eb:8d:da:00</string>
                                                           <key>pi</key>
                                                           <string>4568f1e3-469f-4d8c-a17a-c782cc4f629b</string>
                                                           <key>name</key>
                                                           <string>Wohnzimmer</string>
                                                           <key>model</key>
                                                           <string>Shairport Sync</string>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000266770 "rtsp.c:1426" Responding with content of length 908
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.009944636 "rtsp.c:2063" Connection 2: pair-verify Content-Length 37
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.006837708 "rtsp.c:2098" pair-verify response
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000205625 "rtsp.c:2098"   Response Code: 200.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000091771 "rtsp.c:2098"   Type: "CSeq", content: "1"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000089063 "rtsp.c:2098"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000097187 "rtsp.c:2098"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000111354 "rtsp.c:2098"   No Content Plist. Content length: 140.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000087500 "rtsp.c:1426" Responding with content of length 140
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.043910625 "rtsp.c:2107" Connection 2: pair-setup Content-Length 9
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.110027969 "rtsp.c:2144" pair-setup response
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.002947083 "rtsp.c:2144"   Response Code: 200.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000211667 "rtsp.c:2144"   Type: "CSeq", content: "2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000183750 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000174427 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000230365 "rtsp.c:2144"   No Content Plist. Content length: 409.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000158489 "rtsp.c:1426" Responding with content of length 409
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.026283073 "rtsp.c:2107" Connection 2: pair-setup Content-Length 457
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.139783542 "rtsp.c:2144" pair-setup response
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000160052 "rtsp.c:2144"   Response Code: 200.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000062812 "rtsp.c:2144"   Type: "CSeq", content: "3"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000052604 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000049636 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000050989 "rtsp.c:2144"   No Content Plist. Content length: 69.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000064011 "rtsp.c:1426" Responding with content of length 69
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.007168281 "rtsp.c:1426" Responding with content of length 142
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.009106875 "rtsp.c:1426" Responding with content of length 32
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.005532135 "rtsp.c:2698" Connection 2: SETUP (AirPlay 2)
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000270000 "rtsp.c:2712" Connection 2 SETUP: No "streams" array has been found -- create an event thread and open a TCP port.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000082657 "rtsp.c:2740" Connection 2: SETUP: a "None" setup detected.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000595156 "rtsp.c:2749" Connection 2: Remote Control connection from 2a02:810d:43c0:1bc:b880:fcc1:8c4:e336:61388 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000082292 "rtsp.c:2932" SETUP (no stream) "isRemoteControlOnly" message
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000489166 "rtsp.c:2932"   Type: "Content-Length", content: "386"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000067344 "rtsp.c:2932"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000052135 "rtsp.c:2932"   Type: "CSeq", content: "6"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000053282 "rtsp.c:2932"   Type: "DACP-ID", content: "1BC597D7A0F4FAA7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000049427 "rtsp.c:2932"   Type: "Active-Remote", content: "4229745777"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000057552 "rtsp.c:2932"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001022500 "rtsp.c:2932"   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>isRemoteControlOnly</key>
                                                           <true/>
                                                           <key>osName</key>
                                                           <string>macOS</string>
                                                           <key>sourceVersion</key>
                                                           <string>620.8.2</string>
                                                           <key>timingProtocol</key>
                                                           <string>None</string>
                                                           <key>model</key>
                                                           <string>MacBookPro18,3</string>
                                                           <key>deviceID</key>
                                                           <string>BC:D0:74:2F:E4:98</string>
                                                           <key>osVersion</key>
                                                           <string>12.5</string>
                                                           <key>osBuildVersion</key>
                                                           <string>21G72</string>
                                                           <key>macAddress</key>
                                                           <string>BC:D0:74:2F:E4:98</string>
                                                           <key>sessionUUID</key>
                                                           <string>72F4C26E-BF57-4FCB-B616-CB266BF6D55D</string>
                                                           <key>name</key>
                                                           <string>MacBook Pro von Steffen</string>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000535417 "rtsp.c:2947" Connection 2 SETUP (RC): TCP Remote Control event port opened: 59819.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000883125 "rtp.c:1569" Connection 2 (RC): AP2 Event Receiver started
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000457343 "rtsp.c:3215"  SETUP response
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000102396 "rtsp.c:3215"   Response Code: 200.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000515000 "rtsp.c:3215"   Type: "CSeq", content: "6"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000072604 "rtsp.c:3215"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000437552 "rtsp.c:3215"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000558490 "rtsp.c:3215"   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>eventPort</key>
                                                           <integer>59819</integer>
                                                           <key>timingPort</key>
                                                           <integer>0</integer>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000118594 "rtsp.c:1426" Responding with content of length 76
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.010398646 "rtp.c:1586" Connection 2: rtp_event_receiver accepted a connection on socket 11 and moved to a new socket 12.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.026935625 "rtsp.c:1903" Connection 2: GET /info :: Content-Length 0
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000143645 "rtsp.c:1904" GET request
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000060990 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000057135 "rtsp.c:1904"   Type: "CSeq", content: "7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000051198 "rtsp.c:1904"   Type: "DACP-ID", content: "1BC597D7A0F4FAA7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000047604 "rtsp.c:1904"   Type: "Active-Remote", content: "4229745777"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000046615 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000693958 "rtsp.c:1904"   No Content Plist. Content length: 0.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000479844 "rtsp.c:1563" GET /info:
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000064531 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000320834 "rtsp.c:1563"   Type: "CSeq", content: "7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000080208 "rtsp.c:1563"   Type: "DACP-ID", content: "1BC597D7A0F4FAA7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000051354 "rtsp.c:1563"   Type: "Active-Remote", content: "4229745777"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000046406 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000045000 "rtsp.c:1563"   No Content Plist. Content length: 0.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001340782 "rtsp.c:1702" GET /info Stage 2 Response
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000137135 "rtsp.c:1702"   Response Code: 501.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000056406 "rtsp.c:1702"   Type: "CSeq", content: "7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000053438 "rtsp.c:1702"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000049844 "rtsp.c:1702"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000763073 "rtsp.c:1702"   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>audioLatencies</key>
                                                           <array>
                                                                   <dict>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>100</integer>
                                                                   </dict>
                                                                   <dict>
                                                                           <key>audioType</key>
                                                                           <string>default</string>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>100</integer>
                                                                   </dict>
                                                                   <dict>
                                                                           <key>audioType</key>
                                                                           <string>media</string>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>100</integer>
                                                                   </dict>
                                                                   <dict>
                                                                           <key>audioType</key>
                                                                           <string>media</string>
                                                                           <key>inputLatencyMicros</key>
                                                                           <integer>0</integer>
                                                                           <key>outputLatencyMicros</key>
                                                                           <integer>400000</integer>
                                                                           <key>type</key>
                                                                           <integer>102</integer>
                                                                   </dict>
                                                           </array>
                                                           <key>keepAliveLowPower</key>
                                                           <true/>
                                                           <key>keepAliveSendStatsAsBody</key>
                                                           <true/>
                                                           <key>manufacturer</key>
                                                           <string>Shairport Sync</string>
                                                           <key>nameIsFactoryDefault</key>
                                                           <false/>
                                                           <key>protocolVersion</key>
                                                           <string>1.1</string>
                                                           <key>sdk</key>
                                                           <string>AirPlay;2.0.2</string>
                                                           <key>sourceVersion</key>
                                                           <string>366.0</string>
                                                           <key>statusFlags</key>
                                                           <integer>4</integer>
                                                           <key>features</key>
                                                           <integer>496155701824000</integer>
                                                           <key>deviceID</key>
                                                           <string>b8:27:eb:8d:da:00</string>
                                                           <key>pi</key>
                                                           <string>4568f1e3-469f-4d8c-a17a-c782cc4f629b</string>
                                                           <key>name</key>
                                                           <string>Wohnzimmer</string>
                                                           <key>model</key>
                                                           <string>Shairport Sync</string>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000122031 "rtsp.c:1426" Responding with content of length 573
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.005889844 "rtsp.c:1503" Connection 2: RECORD on Remote Control stream
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.019986666 "rtsp.c:1999" pair-list cb
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000151354 "rtsp.c:2037" pair-list response
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000143021 "rtsp.c:2037"   Response Code: 200.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000054427 "rtsp.c:2037"   Type: "CSeq", content: "11"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000056094 "rtsp.c:2037"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000056771 "rtsp.c:2037"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000056354 "rtsp.c:2037"   No Content Plist. Content length: 3.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000641823 "rtsp.c:1426" Responding with content of length 3
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.009101458 "rtsp.c:2282" POST /configure response:
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000169688 "rtsp.c:2282"   Response Code: 200.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000075208 "rtsp.c:2282"   Type: "CSeq", content: "12"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000067344 "rtsp.c:2282"   Type: "Server", content: "AirTunes/366.0"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000057552 "rtsp.c:2282"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000204375 "rtsp.c:2282"   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>Identifier</key>
                                                           <string>4568f1e3-469f-4d8c-a17a-c782cc4f629b</string>
                                                           <key>Enable_HK_Access_Control</key>
                                                           <true/>
                                                           <key>PublicKey</key>
                                                           <data>
                                                           lzM5lkriNweZoq/YgkBJkY6NecXPBOxUZrqsQVRou0M=
                                                           </data>
                                                           <key>Device_Name</key>
                                                           <string>Wohnzimmer</string>
                                                           <key>Access_Control_Level</key>
                                                           <integer>0</integer>
                                                   </dict>
                                                   </plist>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000103854 "rtsp.c:1426" Responding with content of length 233
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.008469792 "rtsp.c:2568" TEARDOWN:
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000152708 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000074219 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000059167 "rtsp.c:2568"   Type: "CSeq", content: "13"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000058958 "rtsp.c:2568"   Type: "DACP-ID", content: "1BC597D7A0F4FAA7"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000055052 "rtsp.c:2568"   Type: "Active-Remote", content: "4229745777"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000054896 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000098698 "rtsp.c:2568"   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>
                                                   --
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000078333 "rtsp.c:2511" Connection 2: TEARDOWN a Remote Control stream connection.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000059948 "rtsp.c:2520" Connection 2: TEARDOWN Close Data Socket.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000052135 "rtsp.c:2528" Connection 2: TEARDOWN Delete Event Thread.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000584375 "rtp.c:1563" Connection 2: AP2 Event Receiver Cleanup.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000454271 "rtsp.c:2534" Connection 2: TEARDOWN Close Event Socket.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000204375 "rtsp.c:2588" Connection 2: TEARDOWN phase two complete
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.005269167 "rtsp.c:4782" Connection 2: rtsp_conversation_thread_func_cleanup_function called.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000147864 "rtsp.c:2511" Connection 2: TEARDOWN a Remote Control stream connection.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000061563 "rtsp.c:2520" Connection 2: TEARDOWN Close Data Socket.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000054375 "rtsp.c:2534" Connection 2: TEARDOWN Close Event Socket.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.000328385 "rtsp.c:4814" Connection 2: terminating connection from 2a02:810d:43c0:1bc:b880:fcc1:8c4:e336:61388 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 13:57:08 raspberrypi shairport-sync[12116]:          0.001250938 "rtsp.c:4871" Connection 2: Closed.
Aug 10 13:57:09 raspberrypi shairport-sync[12116]:          0.585513802 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_B7CB7DD4085EC234' of type '_dacp._tcp' in domain 'local'.
Aug 10 13:57:09 raspberrypi shairport-sync[12116]:          0.001046458 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_B7CB7DD4085EC234' of type '_dacp._tcp' in domain 'local'.
Aug 10 13:57:18 raspberrypi shairport-sync[12116]:          8.873263226 "activity_monitor.c:172" am_state: am_inactive
Aug 10 13:58:08 raspberrypi shairport-sync[12116]:         50.126903314 "rtsp.c:730" Connection 2: deleted in cleanup.

EDIT: I also get this error when connecting to the airplay device:

Aug 10 14:10:20 raspberrypi shairport-sync[28885]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory

thanks!

mikebrady commented 2 years ago

Thanks for the post. It looks as if the player is actually asking for the play session to be terminated -- that's the TEARDOWN message. In other words, Shairport Sync is obeying the request to end the session.

Can you tell us about the player -- what is it, what version is it, what system is it on and what OS and version it is running, please?

Aug 10 14:10:20 raspberrypi shairport-sync[28885]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory

That's coming from PulseAudio. At a guess, your installation of Shairport Sync is directing its output to the default ALSA output device, which is a pseudo-device routing audio into the PulseAudio system. Does that sound right?

Goldsucher commented 2 years ago

Thanks for the post. It looks as if the player is actually asking for the play session to be terminated -- that's the TEARDOWN message. In other words, Shairport Sync is obeying the request to end the session.

Can you tell us about the player -- what is it, what version is it, what system is it on and what OS and version it is running, please?

Sure: It's a Raspberry Pi 2 Model B with Raspbian GNU/Linux 10 (buster) connected through HDMI to an Onkyo TX-NR656 AVR. It only runs three main tasks: shairport-sync, adguard home and deconz/phoscon

Aug 10 14:10:20 raspberrypi shairport-sync[28885]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory

That's coming from PulseAudio. At a guess, your installation of Shairport Sync is directing its output to the default ALSA output device, which is a pseudo-device routing audio into the PulseAudio system. Does that sound right?

I'm not sure but this could be

mikebrady commented 2 years ago

Thanks. And can you give us details of the audio source as well please — app, version, device, os and version?

Goldsucher commented 2 years ago

ah sorry: I'm streaming Spotify (1.1.91.824.g07f1e963) from a MacBookPro18,3 (M1 Pro 2021) with macOS 12.5. I don't think it's related to Spotify. I will test it with Apple Music.

I think I also had this problem with my older Macbook Pro late 2013. But I'm quite sure this only happens when streaming from a Macbook. Streaming from Homepod and iPhone works well.

mikebrady commented 2 years ago

Thanks again.

mikebrady commented 2 years ago

I don't think it's related to Spotify. I will test it with Apple Music.

Thanks. I would not be quite so sanguine about Spotify, TBH, so it'll be interesting to see. If I get a chance, I'll try it here too.

mikebrady commented 2 years ago

I notice that the Spotify app doesn't make it easy to connect to an AirPlay device. Do you connect via the macOS Sound control?

mikebrady commented 2 years ago

Questions, questions...

Could you post the Shairport Sync version string too please? It's the response to $ shairport-sync -V...

mikebrady commented 2 years ago

So, running the same version of Spotify, without a subscription, on a MacBookPro17,1 with macOS 12.5. Everything seems peachy for the past few hours. If you felt like turning on statistics, it might reveal something, but TBH it's quite a puzzle.

[Update] I wonder if you are doing anything in Spotify such as playing random tracks, or some such? (Just wondering what might cause Spotify or the Mac to ask for a TEARDOWN. In my test, it's just playing Classical New Releases.)

Goldsucher commented 2 years ago

thanks for your investigation!

Yes I just use the macOS sound control. I don't think there is a way to use Airplay directly through the Spotify Mac App

Shairport Sync Version: 4.1-dev-428-g9c8a3328-AirPlay2-OpenSSL-Avahi-ALSA-soxr-sysconfdir:/etc

I just listened to an album without random playback.

I will test it with Apple Music for the next four hours to see if I got the same problem

Goldsucher commented 2 years ago

ok now I had the same issue with Apple Music (Airplay connection via System-Audio, not integrated Apple Music Airplay)

here is the log:


Aug 10 23:09:20 raspberrypi systemd[1]: Finished Clean php session files.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:        293.001250617 "rtsp.c:2568" TEARDOWN:
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000175469 "rtsp.c:2568"   Type: "Content-Length", content: "200"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000073854 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000126146 "rtsp.c:2568"   Type: "CSeq", content: "1784"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000078437 "rtsp.c:2568"   Type: "DACP-ID", content: "F3EC9C296F349031"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000076771 "rtsp.c:2568"   Type: "Active-Remote", content: "219818435"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000679635 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000251407 "rtsp.c:2568"   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>ccCountSender</key>
                                                                         <integer>0</integer>
                                                                         <key>ccCountNonAPSender</key>
                                                                         <integer>0</integer>
                                                                         <key>ioDiscontinuityCount</key>
                                                                         <integer>0</integer>
                                                                         <key>primaryPacketDropCount</key>
                                                                         <integer>0</integer>
                                                                         <key>streamID</key>
                                                                         <integer>0</integer>
                                                                         <key>type</key>
                                                                         <integer>96</integer>
                                                                         <key>ccCountAPSender</key>
                                                                         <integer>0</integer>
                                                                 </dict>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000147239 "rtsp.c:2580" Connection 1: TEARDOWN a PTP stream.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000337448 "audio_alsa.c:1829" alsa: do_close()
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.005412917 "audio_alsa.c:1841" alsa: do_close() -- closing alsa handle
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.003321458 "player.c:1670" Cancelling AP2 timing, control and audio threads...
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000143854 "player.c:1673" Connection 1: Delete Realtime Audio Stream thread
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000328802 "rtp.c:1863" Realtime Audio Receiver Cleanup Start.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000160573 "rtp.c:1866" Connection 60514: closing realtime audio port 439008
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000062292 "rtp.c:1868" Realtime Audio Receiver Cleanup Done.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000328854 "player.c:1687" Connection 1: Delete AirPlay 2 Control thread
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000215052 "rtp.c:1632" Connection 1: AP2 Control Receiver Cleanup.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000117396 "rtp.c:1635" Connection 1: UDP control port 47104 closed.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000401927 "rtp.c:1325" Connection 1: Clear anchor information.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000958646 "player.c:1742" Connection 1: player terminated.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000854271 "rtsp.c:2584" Connection 1: TEARDOWN phase one complete
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.008325469 "rtsp.c:2568" TEARDOWN:
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000141875 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000059427 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000051614 "rtsp.c:2568"   Type: "CSeq", content: "1785"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000048490 "rtsp.c:2568"   Type: "DACP-ID", content: "F3EC9C296F349031"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000048489 "rtsp.c:2568"   Type: "Active-Remote", content: "219818435"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000047188 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000084635 "rtsp.c:2568"   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>
                                                 --
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000069271 "rtsp.c:2511" Connection 1: TEARDOWN a PTP stream connection.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000058646 "rtsp.c:2528" Connection 1: TEARDOWN Delete Event Thread.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000527135 "rtp.c:1563" Connection 1: AP2 Event Receiver Cleanup.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000449948 "rtsp.c:2534" Connection 1: TEARDOWN Close Event Socket.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000992969 "rtsp.c:2555" Connection 1: TEARDOWN mdns_update on PTP stream.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.003366146 "rtsp.c:529" Connection 1: release play lock.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000159427 "rtsp.c:533" Connection 1: release play lock.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000081458 "rtsp.c:2588" Connection 1: TEARDOWN phase two complete
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.017083021 "rtsp.c:4782" Connection 1: rtsp_conversation_thread_func_cleanup_function called.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000173177 "rtsp.c:2511" Connection 1: TEARDOWN a PTP stream connection.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000060625 "rtsp.c:2534" Connection 1: TEARDOWN Close Event Socket.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000874167 "rtsp.c:2555" Connection 1: TEARDOWN mdns_update on PTP stream.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.002791614 "rtsp.c:529" Connection 1: release play lock.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000450573 "rtsp.c:4814" Connection 1: terminating connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:51332 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000799063 "rtsp.c:4871" Connection 1: Closed.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.020332812 "rtsp.c:730" Connection 1: deleted in cleanup.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000317292 "rtsp.c:5319" Connection 4: new connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:52393 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000731979 "rtsp.c:1903" Connection 4: GET /info :: Content-Length 70
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000148646 "rtsp.c:1904" GET request
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000074688 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000066614 "rtsp.c:1904"   Type: "Content-Length", content: "70"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000058490 "rtsp.c:1904"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000052864 "rtsp.c:1904"   Type: "CSeq", content: "0"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000052500 "rtsp.c:1904"   Type: "DACP-ID", content: "954D5EB402FFF95B"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000054063 "rtsp.c:1904"   Type: "Active-Remote", content: "2086861783"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000056719 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000123020 "rtsp.c:1904"   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>qualifier</key>
                                                         <array>
                                                                 <string>txtAirPlay</string>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000071302 "rtsp.c:1563" GET /info:
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000058803 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000054270 "rtsp.c:1563"   Type: "Content-Length", content: "70"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000064323 "rtsp.c:1563"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000060521 "rtsp.c:1563"   Type: "CSeq", content: "0"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000057448 "rtsp.c:1563"   Type: "DACP-ID", content: "954D5EB402FFF95B"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000051354 "rtsp.c:1563"   Type: "Active-Remote", content: "2086861783"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000056511 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000091666 "rtsp.c:1563"   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>qualifier</key>
                                                         <array>
                                                                 <string>txtAirPlay</string>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000066146 "rtsp.c:1573" Connection 4: GET_INFO: Source AirPlay Version is: 620.8.2.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000072344 "rtsp.c:1595" GET /info Stage 1: qualifier: txtAirPlay
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.001811979 "rtsp.c:1677" GET /info Stage 1 Response:
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000142656 "rtsp.c:1677"   Response Code: 501.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000062500 "rtsp.c:1677"   Type: "CSeq", content: "0"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000055209 "rtsp.c:1677"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000058489 "rtsp.c:1677"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000455000 "rtsp.c:1677"   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>audioLatencies</key>
                                                         <array>
                                                                 <dict>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>default</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>102</integer>
                                                                 </dict>
                                                         </array>
                                                         <key>keepAliveLowPower</key>
                                                         <true/>
                                                         <key>keepAliveSendStatsAsBody</key>
                                                         <true/>
                                                         <key>manufacturer</key>
                                                         <string>Shairport Sync</string>
                                                         <key>nameIsFactoryDefault</key>
                                                         <false/>
                                                         <key>protocolVersion</key>
                                                         <string>1.1</string>
                                                         <key>sdk</key>
                                                         <string>AirPlay;2.0.2</string>
                                                         <key>sourceVersion</key>
                                                         <string>366.0</string>
                                                         <key>statusFlags</key>
                                                         <integer>4</integer>
                                                         <key>txtAirPlay</key>
                                                         <data>
                                                         BWFjbD0wGmRldmljZWlkPWI4OjI3OmViOjhkOmRhOjAwG2ZlYXR1cmVzPTB4NDA1QzRB
                                                         MDAsMHgxQzM0MAdyc2Y9MHgwCWZsYWdzPTB4NBRtb2RlbD1TaGFpcnBvcnQgU3luYw1t
                                                         YW51ZmFjdHVyZXI9DXNlcmlhbE51bWJlcj0NcHJvdG92ZXJzPTEuMQ1zcmN2ZXJzPTM2
                                                         Ni4wJ3BpPTgxM2Y3ZTgxLWUwNDMtNDQwZS05NjM5LWJiOWM2NDBlOTVjNihnaWQ9ODEz
                                                         ZjdlODEtZTA0My00NDBlLTk2MzktYmI5YzY0MGU5NWM2BmdjZ2w9MENwaz05NzMzMzk5
                                                         NjRhZTIzNzA3OTlhMmFmZDg4MjQwNDk5MThlOGQ3OWM1Y2YwNGVjNTQ2NmJhYWM0MTU0
                                                         NjhiYjQz
                                                         </data>
                                                         <key>features</key>
                                                         <integer>496155701824000</integer>
                                                         <key>deviceID</key>
                                                         <string>b8:27:eb:8d:da:00</string>
                                                         <key>pi</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>model</key>
                                                         <string>Shairport Sync</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000211771 "rtsp.c:1426" Responding with content of length 908
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.009149792 "rtsp.c:2063" Connection 4: pair-verify Content-Length 37
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.004801510 "rtsp.c:2098" pair-verify response
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000329167 "rtsp.c:2098"   Response Code: 200.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000072500 "rtsp.c:2098"   Type: "CSeq", content: "1"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000057291 "rtsp.c:2098"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000059063 "rtsp.c:2098"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000145417 "rtsp.c:2098"   No Content Plist. Content length: 140.
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.000084270 "rtsp.c:1426" Responding with content of length 140
Aug 10 23:11:48 raspberrypi shairport-sync[544]:          0.102431928 "rtsp.c:2107" Connection 4: pair-setup Content-Length 9
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.121623177 "rtsp.c:2144" pair-setup response
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000155364 "rtsp.c:2144"   Response Code: 200.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000063125 "rtsp.c:2144"   Type: "CSeq", content: "2"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000055677 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000050521 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000053802 "rtsp.c:2144"   No Content Plist. Content length: 409.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000065521 "rtsp.c:1426" Responding with content of length 409
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.127204167 "rtsp.c:2107" Connection 4: pair-setup Content-Length 457
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.175916302 "rtsp.c:2144" pair-setup response
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000169895 "rtsp.c:2144"   Response Code: 200.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000066511 "rtsp.c:2144"   Type: "CSeq", content: "3"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000055937 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000051407 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000052448 "rtsp.c:2144"   No Content Plist. Content length: 69.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000070416 "rtsp.c:1426" Responding with content of length 69
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.007560261 "rtsp.c:1426" Responding with content of length 142
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.007606198 "rtsp.c:1426" Responding with content of length 32
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.011890833 "rtsp.c:2698" Connection 4: SETUP (AirPlay 2)
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000227969 "rtsp.c:2712" Connection 4 SETUP: No "streams" array has been found -- create an event thread and open a TCP port.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000076718 "rtsp.c:2740" Connection 4: SETUP: a "None" setup detected.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000064427 "rtsp.c:2749" Connection 4: Remote Control connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:52393 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000062969 "rtsp.c:2932" SETUP (no stream) "isRemoteControlOnly" message
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000055781 "rtsp.c:2932"   Type: "Content-Length", content: "386"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000053177 "rtsp.c:2932"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000049584 "rtsp.c:2932"   Type: "CSeq", content: "6"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000050260 "rtsp.c:2932"   Type: "DACP-ID", content: "954D5EB402FFF95B"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000051511 "rtsp.c:2932"   Type: "Active-Remote", content: "2086861783"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000051354 "rtsp.c:2932"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000205677 "rtsp.c:2932"   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>isRemoteControlOnly</key>
                                                         <true/>
                                                         <key>osName</key>
                                                         <string>macOS</string>
                                                         <key>sourceVersion</key>
                                                         <string>620.8.2</string>
                                                         <key>timingProtocol</key>
                                                         <string>None</string>
                                                         <key>model</key>
                                                         <string>MacBookPro18,3</string>
                                                         <key>deviceID</key>
                                                         <string>BC:D0:74:2F:E4:98</string>
                                                         <key>osVersion</key>
                                                         <string>12.5</string>
                                                         <key>osBuildVersion</key>
                                                         <string>21G72</string>
                                                         <key>macAddress</key>
                                                         <string>BC:D0:74:2F:E4:98</string>
                                                         <key>sessionUUID</key>
                                                         <string>B1F9438C-08B9-4AEA-9212-FC79A4A6E75B</string>
                                                         <key>name</key>
                                                         <string>MacBook Pro von Steffen</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000194010 "rtsp.c:2947" Connection 4 SETUP (RC): TCP Remote Control event port opened: 53753.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000399427 "rtp.c:1569" Connection 4 (RC): AP2 Event Receiver started
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000063594 "rtsp.c:3215"  SETUP response
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000156563 "rtsp.c:3215"   Response Code: 200.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000078698 "rtsp.c:3215"   Type: "CSeq", content: "6"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000054166 "rtsp.c:3215"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000053125 "rtsp.c:3215"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000118073 "rtsp.c:3215"   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>eventPort</key>
                                                         <integer>53753</integer>
                                                         <key>timingPort</key>
                                                         <integer>0</integer>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000081823 "rtsp.c:1426" Responding with content of length 76
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.013937188 "rtsp.c:1903" Connection 4: GET /info :: Content-Length 0
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000183281 "rtsp.c:1904" GET request
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000074948 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000069531 "rtsp.c:1904"   Type: "CSeq", content: "7"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000065937 "rtsp.c:1904"   Type: "DACP-ID", content: "954D5EB402FFF95B"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000059375 "rtsp.c:1904"   Type: "Active-Remote", content: "2086861783"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000052709 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000057969 "rtsp.c:1904"   No Content Plist. Content length: 0.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000062083 "rtsp.c:1563" GET /info:
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000055312 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000059271 "rtsp.c:1563"   Type: "CSeq", content: "7"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000063177 "rtsp.c:1563"   Type: "DACP-ID", content: "954D5EB402FFF95B"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000058386 "rtsp.c:1563"   Type: "Active-Remote", content: "2086861783"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000052916 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000057344 "rtsp.c:1563"   No Content Plist. Content length: 0.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000633073 "rtp.c:1586" Connection 4: rtp_event_receiver accepted a connection on socket 11 and moved to a new socket 12.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000450156 "rtsp.c:1702" GET /info Stage 2 Response
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000160938 "rtsp.c:1702"   Response Code: 501.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000090521 "rtsp.c:1702"   Type: "CSeq", content: "7"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000085520 "rtsp.c:1702"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000096980 "rtsp.c:1702"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000504687 "rtsp.c:1702"   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>audioLatencies</key>
                                                         <array>
                                                                 <dict>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>default</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>102</integer>
                                                                 </dict>
                                                         </array>
                                                         <key>keepAliveLowPower</key>
                                                         <true/>
                                                         <key>keepAliveSendStatsAsBody</key>
                                                         <true/>
                                                         <key>manufacturer</key>
                                                         <string>Shairport Sync</string>
                                                         <key>nameIsFactoryDefault</key>
                                                         <false/>
                                                         <key>protocolVersion</key>
                                                         <string>1.1</string>
                                                         <key>sdk</key>
                                                         <string>AirPlay;2.0.2</string>
                                                         <key>sourceVersion</key>
                                                         <string>366.0</string>
                                                         <key>statusFlags</key>
                                                         <integer>4</integer>
                                                         <key>features</key>
                                                         <integer>496155701824000</integer>
                                                         <key>deviceID</key>
                                                         <string>b8:27:eb:8d:da:00</string>
                                                         <key>pi</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>model</key>
                                                         <string>Shairport Sync</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000209688 "rtsp.c:1426" Responding with content of length 573
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.005326093 "rtsp.c:1503" Connection 4: RECORD on Remote Control stream
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.116563282 "rtsp.c:1999" pair-list cb
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000172291 "rtsp.c:2037" pair-list response
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000068177 "rtsp.c:2037"   Response Code: 200.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000058021 "rtsp.c:2037"   Type: "CSeq", content: "11"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000052136 "rtsp.c:2037"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000053385 "rtsp.c:2037"   Type: "Content-Type", content: "application/octet-stream"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000051302 "rtsp.c:2037"   No Content Plist. Content length: 3.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000063802 "rtsp.c:1426" Responding with content of length 3
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.007436042 "rtsp.c:2282" POST /configure response:
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000162552 "rtsp.c:2282"   Response Code: 200.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000067240 "rtsp.c:2282"   Type: "CSeq", content: "12"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000052812 "rtsp.c:2282"   Type: "Server", content: "AirTunes/366.0"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000049479 "rtsp.c:2282"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000601615 "rtsp.c:2282"   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>Identifier</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>Enable_HK_Access_Control</key>
                                                         <true/>
                                                         <key>PublicKey</key>
                                                         <data>
                                                         lzM5lkriNweZoq/YgkBJkY6NecXPBOxUZrqsQVRou0M=
                                                         </data>
                                                         <key>Device_Name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>Access_Control_Level</key>
                                                         <integer>0</integer>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000545989 "rtsp.c:1426" Responding with content of length 233
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.007668959 "rtsp.c:2568" TEARDOWN:
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000149635 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000067344 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000058125 "rtsp.c:2568"   Type: "CSeq", content: "13"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000051458 "rtsp.c:2568"   Type: "DACP-ID", content: "954D5EB402FFF95B"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000049427 "rtsp.c:2568"   Type: "Active-Remote", content: "2086861783"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000545417 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000093281 "rtsp.c:2568"   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>
                                                 --
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000435729 "rtsp.c:2511" Connection 4: TEARDOWN a Remote Control stream connection.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000096250 "rtsp.c:2520" Connection 4: TEARDOWN Close Data Socket.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000058021 "rtsp.c:2528" Connection 4: TEARDOWN Delete Event Thread.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000697344 "rtp.c:1563" Connection 4: AP2 Event Receiver Cleanup.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000637396 "rtsp.c:2534" Connection 4: TEARDOWN Close Event Socket.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000213021 "rtsp.c:2588" Connection 4: TEARDOWN phase two complete
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.006454062 "rtsp.c:4782" Connection 4: rtsp_conversation_thread_func_cleanup_function called.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000163646 "rtsp.c:2511" Connection 4: TEARDOWN a Remote Control stream connection.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000075052 "rtsp.c:2520" Connection 4: TEARDOWN Close Data Socket.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000060625 "rtsp.c:2534" Connection 4: TEARDOWN Close Event Socket.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000252865 "rtsp.c:4814" Connection 4: terminating connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:52393 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.000729791 "rtsp.c:4871" Connection 4: Closed.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.380180208 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_F3EC9C296F349031' of type '_dacp._tcp' in domain 'local'.
Aug 10 23:11:49 raspberrypi shairport-sync[544]:          0.001802396 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_F3EC9C296F349031' of type '_dacp._tcp' in domain 'local'.
Aug 10 23:11:58 raspberrypi shairport-sync[544]:          8.817404268 "activity_monitor.c:172" am_state: am_inactive
Aug 10 23:12:48 raspberrypi shairport-sync[544]:         50.116622220 "rtsp.c:730" Connection 4: deleted in cleanup.

``
`
mikebrady commented 2 years ago

Very interesting, thanks. I notice that this is a Realtime stream. This implies, I think, that you chose the Shairport Sync device using the macOS Sound setting and not the Music app's own AirPlay selection icon. Am I right? If so, then it would be worth trying the Music app using its own AirPlay selection icon to choose an AirPlay device. It will then use a Buffered stream, and it would be interesting to see what happens to that.

Separately, I still have no idea why this TEARDOWN might be happening. Hmmm...

My Spotify selection is still playing away...

Goldsucher commented 2 years ago

yes you're right and now I'm already trying it using the Musics app Airplay section.

EDIT: This is what I found in MacOs launch.d log. Don't know if that helps:

2022-08-10 23:11:48.768000 (system) <Warning>: denied lookup: name = com.apple.timed.xpc, requestor = AirPlayXPCHelpe[361], error = 159: Sandbox restriction
2022-08-10 23:11:49.414838 <Notice>: Last log repeated 5 times

2022-08-10 23:11:49.469913 (system) <Warning>: denied lookup: name = com.apple.timed.xpc, requestor = AirPlayXPCHelpe[361], error = 159: Sandbox restriction
2022-08-10 23:11:51.436539 <Notice>: Last log repeated 1 times
mikebrady commented 2 years ago

Thanks. JFYI my Spotify session ended at the end of the selection without any problems at almost exactly 8:00 this morning, after approximately 19 hours and 15 minutes.

I'm afraid I don't understand those log entries or what their significance might be. Do they coincide in time with the issue?

Goldsucher commented 2 years ago

Okay strange 🤔 but thanks for testing!

I'm currently listening using Apple Musics Airplay and weirdly I did't had a disconnect but in the log I see a teardown 5 minutes ago:

Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000106407 "rtsp.c:1426" Responding with content of length 233
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.007911198 "rtsp.c:2568" TEARDOWN:
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000145416 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000065834 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000058333 "rtsp.c:2568"   Type: "CSeq", content: "10"
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000053021 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000089323 "rtsp.c:2568"   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>
                                                 --
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000082291 "rtsp.c:2511" Connection 40: TEARDOWN a Remote Control stream connection.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000069896 "rtsp.c:2520" Connection 40: TEARDOWN Close Data Socket.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000064011 "rtsp.c:2528" Connection 40: TEARDOWN Delete Event Thread.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000652291 "rtp.c:1563" Connection 40: AP2 Event Receiver Cleanup.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000356407 "rtsp.c:2534" Connection 40: TEARDOWN Close Event Socket.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000205677 "rtsp.c:2588" Connection 40: TEARDOWN phase two complete
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.015234895 "rtsp.c:4782" Connection 40: rtsp_conversation_thread_func_cleanup_function called.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000182240 "rtsp.c:2511" Connection 40: TEARDOWN a Remote Control stream connection.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000070052 "rtsp.c:2520" Connection 40: TEARDOWN Close Data Socket.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000053438 "rtsp.c:2534" Connection 40: TEARDOWN Close Event Socket.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000225885 "rtsp.c:4814" Connection 40: terminating connection from 2a02:810d:43c0:1bc:7c1b:b2f3:bb7e:185:52280 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 10:07:09 raspberrypi shairport-sync[544]:          0.000630104 "rtsp.c:4871" Connection 40: Closed.
Aug 11 10:08:08 raspberrypi shairport-sync[544]:         59.544437946 "rtsp.c:730" Connection 40: deleted in cleanup.
mikebrady commented 2 years ago

Thanks. That's closing a Remote Control connection, which Shairport Sync doesn't support. So I think that it is okay -- it happens a lot, and doesn't seem to cause any issue.

mikebrady commented 2 years ago

I see lots of those AirPlayXPC sandbox restriction errors. Seems like a "work in progress" there!

Goldsucher commented 2 years ago

okay, now Apple Music stopped playing / streaming and I had an exclamation mark on the airplay icon but after reconnecting it works again.


Aug 11 10:21:53 raspberrypi shairport-sync[544]:        824.297819373 "rtsp.c:2568" TEARDOWN:
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000196875 "rtsp.c:2568"   Type: "Content-Length", content: "84"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000079844 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056510 "rtsp.c:2568"   Type: "CSeq", content: "1924"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054375 "rtsp.c:2568"   Type: "DACP-ID", content: "D5055441652C1E94"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054115 "rtsp.c:2568"   Type: "Active-Remote", content: "14250169"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000055104 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000158959 "rtsp.c:2568"   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>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000096770 "rtsp.c:2580" Connection 39: TEARDOWN a PTP stream.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000406459 "audio_alsa.c:1829" alsa: do_close()
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.005388750 "audio_alsa.c:1841" alsa: do_close() -- closing alsa handle
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.011846927 "player.c:1670" Cancelling AP2 timing, control and audio threads...
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000141198 "player.c:1679" Connection 39: Delete Buffered Audio Stream thread
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.001091041 "rtp.c:1981" Buffered TCP Reader Thread Exit via Cleanup.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.005556771 "rtp.c:2154" Buffered Audio Receiver Cleanup Start.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000344844 "rtp.c:2158" Connection 39: TCP Buffered Audio port closed: 60229.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000091562 "rtp.c:2160" Buffered Audio Receiver Cleanup Done.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000787605 "player.c:1687" Connection 39: Delete AirPlay 2 Control thread
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000423906 "rtp.c:1632" Connection 39: AP2 Control Receiver Cleanup.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000201354 "rtp.c:1635" Connection 39: UDP control port 35098 closed.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000356458 "rtp.c:1325" Connection 39: Clear anchor information.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000984063 "player.c:1742" Connection 39: player terminated.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000819271 "rtsp.c:2584" Connection 39: TEARDOWN phase one complete
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.062032396 "rtsp.c:2568" TEARDOWN:
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000154322 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000067292 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000057813 "rtsp.c:2568"   Type: "CSeq", content: "1925"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000050104 "rtsp.c:2568"   Type: "DACP-ID", content: "D5055441652C1E94"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000051041 "rtsp.c:2568"   Type: "Active-Remote", content: "14250169"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000051146 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000086927 "rtsp.c:2568"   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>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000068542 "rtsp.c:2511" Connection 39: TEARDOWN a PTP stream connection.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000057656 "rtsp.c:2528" Connection 39: TEARDOWN Delete Event Thread.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000679271 "rtp.c:1563" Connection 39: AP2 Event Receiver Cleanup.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000623750 "rtsp.c:2534" Connection 39: TEARDOWN Close Event Socket.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.001050365 "rtsp.c:2555" Connection 39: TEARDOWN mdns_update on PTP stream.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.004669375 "rtsp.c:529" Connection 39: release play lock.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000202552 "rtsp.c:533" Connection 39: release play lock.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000071198 "rtsp.c:2588" Connection 39: TEARDOWN phase two complete
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.007346666 "rtsp.c:4782" Connection 39: rtsp_conversation_thread_func_cleanup_function called.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000150261 "rtsp.c:2511" Connection 39: TEARDOWN a PTP stream connection.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000067396 "rtsp.c:2534" Connection 39: TEARDOWN Close Event Socket.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000980729 "rtsp.c:2555" Connection 39: TEARDOWN mdns_update on PTP stream.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.003224687 "rtsp.c:529" Connection 39: release play lock.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000417917 "rtsp.c:4814" Connection 39: terminating connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:57599 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000740260 "rtsp.c:4871" Connection 39: Closed.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.042341875 "rtsp.c:730" Connection 39: deleted in cleanup.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000361250 "rtsp.c:5319" Connection 41: new connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:58884 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.001624688 "rtsp.c:1903" Connection 41: GET /info :: Content-Length 70
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000151875 "rtsp.c:1904" GET request
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000064166 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000057032 "rtsp.c:1904"   Type: "Content-Length", content: "70"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054166 "rtsp.c:1904"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000051459 "rtsp.c:1904"   Type: "CSeq", content: "0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000048854 "rtsp.c:1904"   Type: "DACP-ID", content: "AB90D7BD1EAE71"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000051302 "rtsp.c:1904"   Type: "Active-Remote", content: "1189360681"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000048125 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000116823 "rtsp.c:1904"   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>qualifier</key>
                                                         <array>
                                                                 <string>txtAirPlay</string>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000076614 "rtsp.c:1563" GET /info:
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000057292 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000066250 "rtsp.c:1563"   Type: "Content-Length", content: "70"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054271 "rtsp.c:1563"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000055521 "rtsp.c:1563"   Type: "CSeq", content: "0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000049635 "rtsp.c:1563"   Type: "DACP-ID", content: "AB90D7BD1EAE71"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000049844 "rtsp.c:1563"   Type: "Active-Remote", content: "1189360681"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000074635 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000098125 "rtsp.c:1563"   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>qualifier</key>
                                                         <array>
                                                                 <string>txtAirPlay</string>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000063907 "rtsp.c:1573" Connection 41: GET_INFO: Source AirPlay Version is: 620.8.2.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000081145 "rtsp.c:1595" GET /info Stage 1: qualifier: txtAirPlay
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.001630157 "rtsp.c:1677" GET /info Stage 1 Response:
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000140781 "rtsp.c:1677"   Response Code: 501.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000063854 "rtsp.c:1677"   Type: "CSeq", content: "0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000059740 "rtsp.c:1677"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054375 "rtsp.c:1677"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000433802 "rtsp.c:1677"   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>audioLatencies</key>
                                                         <array>
                                                                 <dict>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>default</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>102</integer>
                                                                 </dict>
                                                         </array>
                                                         <key>keepAliveLowPower</key>
                                                         <true/>
                                                         <key>keepAliveSendStatsAsBody</key>
                                                         <true/>
                                                         <key>manufacturer</key>
                                                         <string>Shairport Sync</string>
                                                         <key>nameIsFactoryDefault</key>
                                                         <false/>
                                                         <key>protocolVersion</key>
                                                         <string>1.1</string>
                                                         <key>sdk</key>
                                                         <string>AirPlay;2.0.2</string>
                                                         <key>sourceVersion</key>
                                                         <string>366.0</string>
                                                         <key>statusFlags</key>
                                                         <integer>4</integer>
                                                         <key>txtAirPlay</key>
                                                         <data>
                                                         BWFjbD0wGmRldmljZWlkPWI4OjI3OmViOjhkOmRhOjAwG2ZlYXR1cmVzPTB4NDA1QzRB
                                                         MDAsMHgxQzM0MAdyc2Y9MHgwCWZsYWdzPTB4NBRtb2RlbD1TaGFpcnBvcnQgU3luYw1t
                                                         YW51ZmFjdHVyZXI9DXNlcmlhbE51bWJlcj0NcHJvdG92ZXJzPTEuMQ1zcmN2ZXJzPTM2
                                                         Ni4wJ3BpPTgxM2Y3ZTgxLWUwNDMtNDQwZS05NjM5LWJiOWM2NDBlOTVjNihnaWQ9ODEz
                                                         ZjdlODEtZTA0My00NDBlLTk2MzktYmI5YzY0MGU5NWM2BmdjZ2w9MENwaz05NzMzMzk5
                                                         NjRhZTIzNzA3OTlhMmFmZDg4MjQwNDk5MThlOGQ3OWM1Y2YwNGVjNTQ2NmJhYWM0MTU0
                                                         NjhiYjQz
                                                         </data>
                                                         <key>features</key>
                                                         <integer>496155701824000</integer>
                                                         <key>deviceID</key>
                                                         <string>b8:27:eb:8d:da:00</string>
                                                         <key>pi</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>model</key>
                                                         <string>Shairport Sync</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000395208 "rtsp.c:1426" Responding with content of length 908
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.007083698 "rtsp.c:2063" Connection 41: pair-verify Content-Length 37
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.004735990 "rtsp.c:2098" pair-verify response
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000132916 "rtsp.c:2098"   Response Code: 200.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000058750 "rtsp.c:2098"   Type: "CSeq", content: "1"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000053282 "rtsp.c:2098"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054479 "rtsp.c:2098"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000061250 "rtsp.c:2098"   No Content Plist. Content length: 140.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000068698 "rtsp.c:1426" Responding with content of length 140
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.010762500 "rtsp.c:2107" Connection 41: pair-setup Content-Length 9
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.110215937 "rtsp.c:2144" pair-setup response
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000156042 "rtsp.c:2144"   Response Code: 200.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000066979 "rtsp.c:2144"   Type: "CSeq", content: "2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000055156 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000055521 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000055729 "rtsp.c:2144"   No Content Plist. Content length: 409.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000064740 "rtsp.c:1426" Responding with content of length 409
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.039939896 "rtsp.c:2107" Connection 41: pair-setup Content-Length 457
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.159378750 "rtsp.c:2144" pair-setup response
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000159895 "rtsp.c:2144"   Response Code: 200.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000065938 "rtsp.c:2144"   Type: "CSeq", content: "3"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056042 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056354 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054323 "rtsp.c:2144"   No Content Plist. Content length: 69.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000063593 "rtsp.c:1426" Responding with content of length 69
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.023848646 "rtsp.c:1426" Responding with content of length 142
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.011172552 "rtsp.c:1426" Responding with content of length 32
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.007109688 "rtsp.c:2698" Connection 41: SETUP (AirPlay 2)
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000233802 "rtsp.c:2712" Connection 41 SETUP: No "streams" array has been found -- create an event thread and open a TCP port.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000078073 "rtsp.c:2740" Connection 41: SETUP: a "None" setup detected.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000067291 "rtsp.c:2749" Connection 41: Remote Control connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:58884 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000064844 "rtsp.c:2932" SETUP (no stream) "isRemoteControlOnly" message
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000063021 "rtsp.c:2932"   Type: "Content-Length", content: "386"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056458 "rtsp.c:2932"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056355 "rtsp.c:2932"   Type: "CSeq", content: "6"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056510 "rtsp.c:2932"   Type: "DACP-ID", content: "AB90D7BD1EAE71"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000058333 "rtsp.c:2932"   Type: "Active-Remote", content: "1189360681"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056198 "rtsp.c:2932"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000295104 "rtsp.c:2932"   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>isRemoteControlOnly</key>
                                                         <true/>
                                                         <key>osName</key>
                                                         <string>macOS</string>
                                                         <key>sourceVersion</key>
                                                         <string>620.8.2</string>
                                                         <key>timingProtocol</key>
                                                         <string>None</string>
                                                         <key>model</key>
                                                         <string>MacBookPro18,3</string>
                                                         <key>deviceID</key>
                                                         <string>BC:D0:74:2F:E4:98</string>
                                                         <key>osVersion</key>
                                                         <string>12.5</string>
                                                         <key>osBuildVersion</key>
                                                         <string>21G72</string>
                                                         <key>macAddress</key>
                                                         <string>BC:D0:74:2F:E4:98</string>
                                                         <key>sessionUUID</key>
                                                         <string>17564C74-BFE9-4EDD-B023-A7697F0298E9</string>
                                                         <key>name</key>
                                                         <string>MacBook Pro von Steffen</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000211250 "rtsp.c:2947" Connection 41 SETUP (RC): TCP Remote Control event port opened: 43705.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000398542 "rtp.c:1569" Connection 41 (RC): AP2 Event Receiver started
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000069583 "rtsp.c:3215"  SETUP response
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000079219 "rtsp.c:3215"   Response Code: 200.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000065104 "rtsp.c:3215"   Type: "CSeq", content: "6"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000064844 "rtsp.c:3215"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000057188 "rtsp.c:3215"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000118020 "rtsp.c:3215"   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>eventPort</key>
                                                         <integer>43705</integer>
                                                         <key>timingPort</key>
                                                         <integer>0</integer>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000088334 "rtsp.c:1426" Responding with content of length 76
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.013063021 "rtp.c:1586" Connection 41: rtp_event_receiver accepted a connection on socket 11 and moved to a new socket 12.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.001439270 "rtsp.c:1903" Connection 41: GET /info :: Content-Length 0
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000161615 "rtsp.c:1904" GET request
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000074167 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000060885 "rtsp.c:1904"   Type: "CSeq", content: "7"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000058646 "rtsp.c:1904"   Type: "DACP-ID", content: "AB90D7BD1EAE71"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000061354 "rtsp.c:1904"   Type: "Active-Remote", content: "1189360681"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000058073 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056823 "rtsp.c:1904"   No Content Plist. Content length: 0.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000059375 "rtsp.c:1563" GET /info:
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000175521 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000080833 "rtsp.c:1563"   Type: "CSeq", content: "7"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000056771 "rtsp.c:1563"   Type: "DACP-ID", content: "AB90D7BD1EAE71"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000057917 "rtsp.c:1563"   Type: "Active-Remote", content: "1189360681"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000058177 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000060312 "rtsp.c:1563"   No Content Plist. Content length: 0.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.001122031 "rtsp.c:1702" GET /info Stage 2 Response
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000143959 "rtsp.c:1702"   Response Code: 501.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000067187 "rtsp.c:1702"   Type: "CSeq", content: "7"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000321615 "rtsp.c:1702"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000142396 "rtsp.c:1702"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000457968 "rtsp.c:1702"   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>audioLatencies</key>
                                                         <array>
                                                                 <dict>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>default</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>102</integer>
                                                                 </dict>
                                                         </array>
                                                         <key>keepAliveLowPower</key>
                                                         <true/>
                                                         <key>keepAliveSendStatsAsBody</key>
                                                         <true/>
                                                         <key>manufacturer</key>
                                                         <string>Shairport Sync</string>
                                                         <key>nameIsFactoryDefault</key>
                                                         <false/>
                                                         <key>protocolVersion</key>
                                                         <string>1.1</string>
                                                         <key>sdk</key>
                                                         <string>AirPlay;2.0.2</string>
                                                         <key>sourceVersion</key>
                                                         <string>366.0</string>
                                                         <key>statusFlags</key>
                                                         <integer>4</integer>
                                                         <key>features</key>
                                                         <integer>496155701824000</integer>
                                                         <key>deviceID</key>
                                                         <string>b8:27:eb:8d:da:00</string>
                                                         <key>pi</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>model</key>
                                                         <string>Shairport Sync</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000120157 "rtsp.c:1426" Responding with content of length 573
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.006013072 "rtsp.c:1503" Connection 41: RECORD on Remote Control stream
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.112551563 "rtsp.c:1999" pair-list cb
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000182448 "rtsp.c:2037" pair-list response
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000068281 "rtsp.c:2037"   Response Code: 200.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054844 "rtsp.c:2037"   Type: "CSeq", content: "11"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000050989 "rtsp.c:2037"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000050209 "rtsp.c:2037"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054583 "rtsp.c:2037"   No Content Plist. Content length: 3.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000064948 "rtsp.c:1426" Responding with content of length 3
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.011555938 "rtsp.c:2282" POST /configure response:
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000201145 "rtsp.c:2282"   Response Code: 200.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000066875 "rtsp.c:2282"   Type: "CSeq", content: "12"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000059480 "rtsp.c:2282"   Type: "Server", content: "AirTunes/366.0"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000053072 "rtsp.c:2282"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000205417 "rtsp.c:2282"   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>Identifier</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>Enable_HK_Access_Control</key>
                                                         <true/>
                                                         <key>PublicKey</key>
                                                         <data>
                                                         lzM5lkriNweZoq/YgkBJkY6NecXPBOxUZrqsQVRou0M=
                                                         </data>
                                                         <key>Device_Name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>Access_Control_Level</key>
                                                         <integer>0</integer>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000106823 "rtsp.c:1426" Responding with content of length 233
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.011646615 "rtsp.c:2568" TEARDOWN:
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000204843 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000077813 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000064896 "rtsp.c:2568"   Type: "CSeq", content: "13"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000054114 "rtsp.c:2568"   Type: "DACP-ID", content: "AB90D7BD1EAE71"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000051719 "rtsp.c:2568"   Type: "Active-Remote", content: "1189360681"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000084896 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000106562 "rtsp.c:2568"   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>
                                                 --
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000084636 "rtsp.c:2511" Connection 41: TEARDOWN a Remote Control stream connection.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000064271 "rtsp.c:2520" Connection 41: TEARDOWN Close Data Socket.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000053541 "rtsp.c:2528" Connection 41: TEARDOWN Delete Event Thread.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000738177 "rtp.c:1563" Connection 41: AP2 Event Receiver Cleanup.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000641042 "rtsp.c:2534" Connection 41: TEARDOWN Close Event Socket.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000319896 "rtsp.c:2588" Connection 41: TEARDOWN phase two complete
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.005870260 "rtsp.c:4782" Connection 41: rtsp_conversation_thread_func_cleanup_function called.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000164011 "rtsp.c:2511" Connection 41: TEARDOWN a Remote Control stream connection.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000063750 "rtsp.c:2520" Connection 41: TEARDOWN Close Data Socket.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000066666 "rtsp.c:2534" Connection 41: TEARDOWN Close Event Socket.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000255730 "rtsp.c:4814" Connection 41: terminating connection from 2a02:810d:43c0:1bc:8079:41fd:f2ec:23cd:58884 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 10:21:53 raspberrypi shairport-sync[544]:          0.000730364 "rtsp.c:4871" Connection 41: Closed.
Aug 11 10:21:54 raspberrypi shairport-sync[544]:          0.575241562 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_D5055441652C1E94' of type '_dacp._tcp' in domain 'local'.
Aug 11 10:21:54 raspberrypi shairport-sync[544]:          0.001681094 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_D5055441652C1E94' of type '_dacp._tcp' in domain 'local'.
Aug 11 10:22:03 raspberrypi shairport-sync[544]:          8.743420882 "activity_monitor.c:172" am_state: am_inactive
Aug 11 10:22:53 raspberrypi shairport-sync[544]:         50.171203835 "rtsp.c:730" Connection 41: deleted in cleanup.
mikebrady commented 2 years ago

Thanks. That's very interesting. The exclamation mark means that this is an unexpected event from the point of view of the Apple Music app. In my experience, this only happens when Shairport Sync ("SPS") stops without warning.

Thinking about this, the only way the Music app "knows" that the SPS device is gone is because the network connection(s) disappear.

Since SPS is still running (you can see it processing the TEARDOWN and the Remote Control connection that follows) I'm going to make a guess that there is some some kind of network interruption from time to time that causes the player (Apple Music in this case) to "think" that SPS has disappeared. Maybe a faulty cable or an old router or a faulty or over-restrictive firewall or other program is the cause, though why this should only happen with the Mac, I don't know. There's some stuff in the troubleshooting guide about this, but I guess you've looked there.

mikebrady commented 2 years ago

(I'm afraid I'll be offline for most of the rest of the day. -- I'll try to get a look in, but not sure.)

Goldsucher commented 2 years ago

Okay thanks!

I'm quite sure that there is no network interruption. The RPi is connected via Ethernet, my Router is a FRITZ!Box 6591 Cable and I don't use any special Firewall. The only thing is Adguard Home but that's only for DNS requests so it shouldn't affect shairport-sync.

I tested it with my iPhone (Apple Music and Spotify App) and had no issues.

I keep testing and keep you updated.

Goldsucher commented 2 years ago

yeah still the same issue. Tested it with a youtube livestream from browser. So it's not app related: I think the log is the same as always. So I have no idea anymore. Maybe something is wrong with my built. Will there be pre-built versions with airplay 2 support in the future?

log again:

Aug 11 22:54:36 raspberrypi shairport-sync[544]:       1294.071501694 "rtsp.c:2568" TEARDOWN:
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000204844 "rtsp.c:2568"   Type: "Content-Length", content: "200"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000092656 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000077552 "rtsp.c:2568"   Type: "CSeq", content: "660"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000066823 "rtsp.c:2568"   Type: "DACP-ID", content: "3C59056CFFD7817B"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000069010 "rtsp.c:2568"   Type: "Active-Remote", content: "10666213"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000067709 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000258021 "rtsp.c:2568"   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>ccCountSender</key>
                                                                         <integer>0</integer>
                                                                         <key>ccCountNonAPSender</key>
                                                                         <integer>0</integer>
                                                                         <key>ioDiscontinuityCount</key>
                                                                         <integer>0</integer>
                                                                         <key>primaryPacketDropCount</key>
                                                                         <integer>0</integer>
                                                                         <key>streamID</key>
                                                                         <integer>0</integer>
                                                                         <key>type</key>
                                                                         <integer>96</integer>
                                                                         <key>ccCountAPSender</key>
                                                                         <integer>0</integer>
                                                                 </dict>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000168489 "rtsp.c:2580" Connection 89: TEARDOWN a PTP stream.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000559792 "audio_alsa.c:1829" alsa: do_close()
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.005445521 "audio_alsa.c:1841" alsa: do_close() -- closing alsa handle
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.006182448 "player.c:1670" Cancelling AP2 timing, control and audio threads...
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000125468 "player.c:1673" Connection 89: Delete Realtime Audio Stream thread
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000349844 "rtp.c:1863" Realtime Audio Receiver Cleanup Start.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000191979 "rtp.c:1866" Connection 43049: closing realtime audio port 439008
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000077813 "rtp.c:1868" Realtime Audio Receiver Cleanup Done.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000289218 "player.c:1687" Connection 89: Delete AirPlay 2 Control thread
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000274844 "rtp.c:1632" Connection 89: AP2 Control Receiver Cleanup.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000179948 "rtp.c:1635" Connection 89: UDP control port 50917 closed.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000246771 "rtp.c:1325" Connection 89: Clear anchor information.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.001136510 "player.c:1742" Connection 89: player terminated.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000475209 "rtsp.c:2584" Connection 89: TEARDOWN phase one complete
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.007406614 "rtsp.c:2568" TEARDOWN:
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000166927 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000087240 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000074115 "rtsp.c:2568"   Type: "CSeq", content: "661"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000066979 "rtsp.c:2568"   Type: "DACP-ID", content: "3C59056CFFD7817B"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000070781 "rtsp.c:2568"   Type: "Active-Remote", content: "10666213"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000487500 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000152135 "rtsp.c:2568"   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>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000108177 "rtsp.c:2511" Connection 89: TEARDOWN a PTP stream connection.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000077084 "rtsp.c:2528" Connection 89: TEARDOWN Delete Event Thread.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000909010 "rtp.c:1563" Connection 89: AP2 Event Receiver Cleanup.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000827500 "rtsp.c:2534" Connection 89: TEARDOWN Close Event Socket.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.001352761 "rtsp.c:2555" Connection 89: TEARDOWN mdns_update on PTP stream.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.004336198 "rtsp.c:529" Connection 89: release play lock.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000200989 "rtsp.c:533" Connection 89: release play lock.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000090313 "rtsp.c:2588" Connection 89: TEARDOWN phase two complete
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.011598854 "rtsp.c:4782" Connection 89: rtsp_conversation_thread_func_cleanup_function called.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000213854 "rtsp.c:2511" Connection 89: TEARDOWN a PTP stream connection.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000122031 "rtsp.c:2534" Connection 89: TEARDOWN Close Event Socket.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.001293542 "rtsp.c:2555" Connection 89: TEARDOWN mdns_update on PTP stream.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.004253489 "rtsp.c:529" Connection 89: release play lock.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000848803 "rtsp.c:4814" Connection 89: terminating connection from 2a02:810d:43c0:1bc:d0dc:8292:40c6:48fc:57195 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.001251302 "rtsp.c:4871" Connection 89: Closed.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.045170833 "rtsp.c:730" Connection 89: deleted in cleanup.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000357240 "rtsp.c:5319" Connection 90: new connection from 2a02:810d:43c0:1bc:d0dc:8292:40c6:48fc:57662 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000799791 "rtsp.c:1903" Connection 90: GET /info :: Content-Length 70
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000158438 "rtsp.c:1904" GET request
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000068802 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000065573 "rtsp.c:1904"   Type: "Content-Length", content: "70"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000064948 "rtsp.c:1904"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000066250 "rtsp.c:1904"   Type: "CSeq", content: "0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000053750 "rtsp.c:1904"   Type: "DACP-ID", content: "C8E883CA865FD67F"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000058698 "rtsp.c:1904"   Type: "Active-Remote", content: "4073940867"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000088177 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000140677 "rtsp.c:1904"   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>qualifier</key>
                                                         <array>
                                                                 <string>txtAirPlay</string>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000093021 "rtsp.c:1563" GET /info:
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000066145 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000056250 "rtsp.c:1563"   Type: "Content-Length", content: "70"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000056927 "rtsp.c:1563"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000061719 "rtsp.c:1563"   Type: "CSeq", content: "0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000052604 "rtsp.c:1563"   Type: "DACP-ID", content: "C8E883CA865FD67F"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000079167 "rtsp.c:1563"   Type: "Active-Remote", content: "4073940867"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000055990 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000100416 "rtsp.c:1563"   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>qualifier</key>
                                                         <array>
                                                                 <string>txtAirPlay</string>
                                                         </array>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000072969 "rtsp.c:1573" Connection 90: GET_INFO: Source AirPlay Version is: 620.8.2.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000345781 "rtsp.c:1595" GET /info Stage 1: qualifier: txtAirPlay
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.001810052 "rtsp.c:1677" GET /info Stage 1 Response:
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000189063 "rtsp.c:1677"   Response Code: 501.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000080885 "rtsp.c:1677"   Type: "CSeq", content: "0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000070000 "rtsp.c:1677"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000070052 "rtsp.c:1677"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000481771 "rtsp.c:1677"   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>audioLatencies</key>
                                                         <array>
                                                                 <dict>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>default</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>102</integer>
                                                                 </dict>
                                                         </array>
                                                         <key>keepAliveLowPower</key>
                                                         <true/>
                                                         <key>keepAliveSendStatsAsBody</key>
                                                         <true/>
                                                         <key>manufacturer</key>
                                                         <string>Shairport Sync</string>
                                                         <key>nameIsFactoryDefault</key>
                                                         <false/>
                                                         <key>protocolVersion</key>
                                                         <string>1.1</string>
                                                         <key>sdk</key>
                                                         <string>AirPlay;2.0.2</string>
                                                         <key>sourceVersion</key>
                                                         <string>366.0</string>
                                                         <key>statusFlags</key>
                                                         <integer>4</integer>
                                                         <key>txtAirPlay</key>
                                                         <data>
                                                         BWFjbD0wGmRldmljZWlkPWI4OjI3OmViOjhkOmRhOjAwG2ZlYXR1cmVzPTB4NDA1QzRB
                                                         MDAsMHgxQzM0MAdyc2Y9MHgwCWZsYWdzPTB4NBRtb2RlbD1TaGFpcnBvcnQgU3luYw1t
                                                         YW51ZmFjdHVyZXI9DXNlcmlhbE51bWJlcj0NcHJvdG92ZXJzPTEuMQ1zcmN2ZXJzPTM2
                                                         Ni4wJ3BpPTgxM2Y3ZTgxLWUwNDMtNDQwZS05NjM5LWJiOWM2NDBlOTVjNihnaWQ9ODEz
                                                         ZjdlODEtZTA0My00NDBlLTk2MzktYmI5YzY0MGU5NWM2BmdjZ2w9MENwaz05NzMzMzk5
                                                         NjRhZTIzNzA3OTlhMmFmZDg4MjQwNDk5MThlOGQ3OWM1Y2YwNGVjNTQ2NmJhYWM0MTU0
                                                         NjhiYjQz
                                                         </data>
                                                         <key>features</key>
                                                         <integer>496155701824000</integer>
                                                         <key>deviceID</key>
                                                         <string>b8:27:eb:8d:da:00</string>
                                                         <key>pi</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>model</key>
                                                         <string>Shairport Sync</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000196302 "rtsp.c:1426" Responding with content of length 908
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.007741094 "rtsp.c:2063" Connection 90: pair-verify Content-Length 37
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.005231198 "rtsp.c:2098" pair-verify response
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000163437 "rtsp.c:2098"   Response Code: 200.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000073750 "rtsp.c:2098"   Type: "CSeq", content: "1"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000064271 "rtsp.c:2098"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000064011 "rtsp.c:2098"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000059323 "rtsp.c:2098"   No Content Plist. Content length: 140.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000071823 "rtsp.c:1426" Responding with content of length 140
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.042264479 "rtsp.c:2107" Connection 90: pair-setup Content-Length 9
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.111262552 "rtsp.c:2144" pair-setup response
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000164062 "rtsp.c:2144"   Response Code: 200.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000072813 "rtsp.c:2144"   Type: "CSeq", content: "2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000061666 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000056355 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000053385 "rtsp.c:2144"   No Content Plist. Content length: 409.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000067292 "rtsp.c:1426" Responding with content of length 409
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.025127395 "rtsp.c:2107" Connection 90: pair-setup Content-Length 457
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.143050834 "rtsp.c:2144" pair-setup response
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000170521 "rtsp.c:2144"   Response Code: 200.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000067135 "rtsp.c:2144"   Type: "CSeq", content: "3"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000054948 "rtsp.c:2144"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000053958 "rtsp.c:2144"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000054115 "rtsp.c:2144"   No Content Plist. Content length: 69.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000067708 "rtsp.c:1426" Responding with content of length 69
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.006827708 "rtsp.c:1426" Responding with content of length 142
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.006978386 "rtsp.c:1426" Responding with content of length 32
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.005886302 "rtsp.c:2698" Connection 90: SETUP (AirPlay 2)
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000228646 "rtsp.c:2712" Connection 90 SETUP: No "streams" array has been found -- create an event thread and open a TCP port.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000079896 "rtsp.c:2740" Connection 90: SETUP: a "None" setup detected.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000591354 "rtsp.c:2749" Connection 90: Remote Control connection from 2a02:810d:43c0:1bc:d0dc:8292:40c6:48fc:57662 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000063802 "rtsp.c:2932" SETUP (no stream) "isRemoteControlOnly" message
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000053073 "rtsp.c:2932"   Type: "Content-Length", content: "386"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000050781 "rtsp.c:2932"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000516146 "rtsp.c:2932"   Type: "CSeq", content: "6"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000084219 "rtsp.c:2932"   Type: "DACP-ID", content: "C8E883CA865FD67F"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000055989 "rtsp.c:2932"   Type: "Active-Remote", content: "4073940867"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000454323 "rtsp.c:2932"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000548958 "rtsp.c:2932"   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>isRemoteControlOnly</key>
                                                         <true/>
                                                         <key>osName</key>
                                                         <string>macOS</string>
                                                         <key>sourceVersion</key>
                                                         <string>620.8.2</string>
                                                         <key>timingProtocol</key>
                                                         <string>None</string>
                                                         <key>model</key>
                                                         <string>MacBookPro18,3</string>
                                                         <key>deviceID</key>
                                                         <string>BC:D0:74:2F:E4:98</string>
                                                         <key>osVersion</key>
                                                         <string>12.5</string>
                                                         <key>osBuildVersion</key>
                                                         <string>21G72</string>
                                                         <key>macAddress</key>
                                                         <string>BC:D0:74:2F:E4:98</string>
                                                         <key>sessionUUID</key>
                                                         <string>B604C989-43DB-4B9D-BCF0-BC83B6D491BE</string>
                                                         <key>name</key>
                                                         <string>MacBook Pro von Steffen</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000308959 "rtsp.c:2947" Connection 90 SETUP (RC): TCP Remote Control event port opened: 40883.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000743802 "rtp.c:1569" Connection 90 (RC): AP2 Event Receiver started
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000081927 "rtsp.c:3215"  SETUP response
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000540312 "rtsp.c:3215"   Response Code: 200.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000064167 "rtsp.c:3215"   Type: "CSeq", content: "6"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000054948 "rtsp.c:3215"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000501771 "rtsp.c:3215"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000158750 "rtsp.c:3215"   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>eventPort</key>
                                                         <integer>40883</integer>
                                                         <key>timingPort</key>
                                                         <integer>0</integer>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000435989 "rtsp.c:1426" Responding with content of length 76
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.011916355 "rtp.c:1586" Connection 90: rtp_event_receiver accepted a connection on socket 11 and moved to a new socket 12.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.001173489 "rtsp.c:1903" Connection 90: GET /info :: Content-Length 0
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000144011 "rtsp.c:1904" GET request
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000065208 "rtsp.c:1904"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000055833 "rtsp.c:1904"   Type: "CSeq", content: "7"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000051094 "rtsp.c:1904"   Type: "DACP-ID", content: "C8E883CA865FD67F"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000451042 "rtsp.c:1904"   Type: "Active-Remote", content: "4073940867"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000102187 "rtsp.c:1904"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000059271 "rtsp.c:1904"   No Content Plist. Content length: 0.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000054896 "rtsp.c:1563" GET /info:
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000051406 "rtsp.c:1563"   Type: "X-Apple-ProtocolVersion", content: "1"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000476511 "rtsp.c:1563"   Type: "CSeq", content: "7"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000055989 "rtsp.c:1563"   Type: "DACP-ID", content: "C8E883CA865FD67F"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000060677 "rtsp.c:1563"   Type: "Active-Remote", content: "4073940867"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000055052 "rtsp.c:1563"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000488959 "rtsp.c:1563"   No Content Plist. Content length: 0.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.002013073 "rtsp.c:1702" GET /info Stage 2 Response
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000253072 "rtsp.c:1702"   Response Code: 501.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000485209 "rtsp.c:1702"   Type: "CSeq", content: "7"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000061510 "rtsp.c:1702"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000054531 "rtsp.c:1702"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.001148438 "rtsp.c:1702"   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>audioLatencies</key>
                                                         <array>
                                                                 <dict>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>default</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>100</integer>
                                                                 </dict>
                                                                 <dict>
                                                                         <key>audioType</key>
                                                                         <string>media</string>
                                                                         <key>inputLatencyMicros</key>
                                                                         <integer>0</integer>
                                                                         <key>outputLatencyMicros</key>
                                                                         <integer>400000</integer>
                                                                         <key>type</key>
                                                                         <integer>102</integer>
                                                                 </dict>
                                                         </array>
                                                         <key>keepAliveLowPower</key>
                                                         <true/>
                                                         <key>keepAliveSendStatsAsBody</key>
                                                         <true/>
                                                         <key>manufacturer</key>
                                                         <string>Shairport Sync</string>
                                                         <key>nameIsFactoryDefault</key>
                                                         <false/>
                                                         <key>protocolVersion</key>
                                                         <string>1.1</string>
                                                         <key>sdk</key>
                                                         <string>AirPlay;2.0.2</string>
                                                         <key>sourceVersion</key>
                                                         <string>366.0</string>
                                                         <key>statusFlags</key>
                                                         <integer>4</integer>
                                                         <key>features</key>
                                                         <integer>496155701824000</integer>
                                                         <key>deviceID</key>
                                                         <string>b8:27:eb:8d:da:00</string>
                                                         <key>pi</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>model</key>
                                                         <string>Shairport Sync</string>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000178854 "rtsp.c:1426" Responding with content of length 573
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.007059792 "rtsp.c:1503" Connection 90: RECORD on Remote Control stream
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.014139531 "rtsp.c:1999" pair-list cb
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000162344 "rtsp.c:2037" pair-list response
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000066614 "rtsp.c:2037"   Response Code: 200.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000136198 "rtsp.c:2037"   Type: "CSeq", content: "10"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000687500 "rtsp.c:2037"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000126771 "rtsp.c:2037"   Type: "Content-Type", content: "application/octet-stream"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000064636 "rtsp.c:2037"   No Content Plist. Content length: 3.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000069635 "rtsp.c:1426" Responding with content of length 3
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.008819167 "rtsp.c:2282" POST /configure response:
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000165781 "rtsp.c:2282"   Response Code: 200.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000064635 "rtsp.c:2282"   Type: "CSeq", content: "11"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000055938 "rtsp.c:2282"   Type: "Server", content: "AirTunes/366.0"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000051666 "rtsp.c:2282"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000818438 "rtsp.c:2282"   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>Identifier</key>
                                                         <string>813f7e81-e043-440e-9639-bb9c640e95c6</string>
                                                         <key>Enable_HK_Access_Control</key>
                                                         <true/>
                                                         <key>PublicKey</key>
                                                         <data>
                                                         lzM5lkriNweZoq/YgkBJkY6NecXPBOxUZrqsQVRou0M=
                                                         </data>
                                                         <key>Device_Name</key>
                                                         <string>Wohnzimmer</string>
                                                         <key>Access_Control_Level</key>
                                                         <integer>0</integer>
                                                 </dict>
                                                 </plist>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000139115 "rtsp.c:1426" Responding with content of length 233
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.014272968 "rtsp.c:2568" TEARDOWN:
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000168907 "rtsp.c:2568"   Type: "Content-Length", content: "42"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000075052 "rtsp.c:2568"   Type: "Content-Type", content: "application/x-apple-binary-plist"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000067604 "rtsp.c:2568"   Type: "CSeq", content: "13"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000069739 "rtsp.c:2568"   Type: "DACP-ID", content: "C8E883CA865FD67F"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000059271 "rtsp.c:2568"   Type: "Active-Remote", content: "4073940867"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000059115 "rtsp.c:2568"   Type: "User-Agent", content: "AirPlay/620.8.2"
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000102291 "rtsp.c:2568"   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>
                                                 --
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000836615 "rtsp.c:2511" Connection 90: TEARDOWN a Remote Control stream connection.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000097865 "rtsp.c:2520" Connection 90: TEARDOWN Close Data Socket.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000060729 "rtsp.c:2528" Connection 90: TEARDOWN Delete Event Thread.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000611250 "rtp.c:1563" Connection 90: AP2 Event Receiver Cleanup.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000475677 "rtsp.c:2534" Connection 90: TEARDOWN Close Event Socket.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000205052 "rtsp.c:2588" Connection 90: TEARDOWN phase two complete
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.006928802 "rtsp.c:4782" Connection 90: rtsp_conversation_thread_func_cleanup_function called.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000165521 "rtsp.c:2511" Connection 90: TEARDOWN a Remote Control stream connection.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000687344 "rtsp.c:2520" Connection 90: TEARDOWN Close Data Socket.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000228229 "rtsp.c:2534" Connection 90: TEARDOWN Close Event Socket.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000382291 "rtsp.c:4814" Connection 90: terminating connection from 2a02:810d:43c0:1bc:d0dc:8292:40c6:48fc:57662 to self at 2a02:810d:43c0:1bc:6e8d:5439:4f8b:25ca:7000.
Aug 11 22:54:36 raspberrypi shairport-sync[544]:          0.000767969 "rtsp.c:4871" Connection 90: Closed.
Aug 11 22:54:37 raspberrypi shairport-sync[544]:          0.602718281 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_3C59056CFFD7817B' of type '_dacp._tcp' in domain 'local'.
Aug 11 22:54:37 raspberrypi shairport-sync[544]:          0.000426198 "mdns_avahi.c:161" (Browser) REMOVE: service 'iTunes_Ctrl_3C59056CFFD7817B' of type '_dacp._tcp' in domain 'local'.
Aug 11 22:54:46 raspberrypi shairport-sync[544]:          8.870228018 "activity_monitor.c:172" am_state: am_inactive
mikebrady commented 2 years ago

Thanks for the updates. It’s always worth ensuring the router’s firmware is up to date.

Goldsucher commented 2 years ago

yeah but my router has the newest firmware

mikebrady commented 2 years ago

Hmm. If there was a way of temporarily bypassing the router and going directly between the Mac and the Pi, it would allow you to be certain that the Mac and the Pi were both okay. Then you could add in the rest of the existing system gradually. It would be a bit messy but it might offer a pathway to resolving the issue.

Goldsucher commented 2 years ago

Hi @mikebrady sorry for the late response. I'm not sure how to directly connect to the RPi? Could you give me a hint?

Meanwhile I tested it with another shairport-sync installation on another RPi but I got the same error. I also found some other errors in MacOS log, maybe they help:

APAdvertiserInfoCopyAirPlayData:737: got error -6705/0xFFFFE5CF kParamErr

_APAdvertiserInfoAddStringToTXTRecord:634: false condition

Sandbox: AirPlayXPCHelper(583) deny(1) mach-lookup com.apple.timed.xpc

Sandbox: AirPlayXPCHelper(583) deny(1) file-read-data /Library/Application Support/CrashReporter/DiagnosticMessagesHistory.plist

Couldn't read values in CFPrefsPlistSource<0x14c908370> (Domain: com.apple.mediaremote, User: kCFPreferencesCurrentUser, ByHost: No, Container: (null), Contents Need Refresh: Yes): accessing these preferences requires user-preference-read or file-read-data sandbox access

maybe here the missing pairing ID is a problem?

### Ignoring device found without pairing ID: 202481595177472, 'Wohnzimmer'

thanks for your help!

mikebrady commented 2 years ago

Thanks for the update. I'm afraid I have no idea what those messages mean. Presumably they are helpful to Apple's developers, but they mean nothing to me.

I'm not sure how to directly connect to the RPi? Could you give me a hint?

I was thinking of connecting the Mac directly to the Pi using an Ethernet cable. You'd need an adapter at the Mac end, of course. You'd also have to go without the other services on the Pi while you were testing, so it might be awkward. If Shairport Sync worked without problem in that setup, it would strongly imply that the router was at fault.

Goldsucher commented 2 years ago

Hi @mikebrady I have made a little progress: Apparently the issue isn't related to shairport-sync. You were right, It's probably a router-problem: Here they say, disable WWM in router settings fixes the disconnects: https://www.reddit.com/r/HomePod/comments/qc4l5l/airplay_cuts_out_all_the_time_from_macbook_pro_m1/

Unfortunately my router does not have this setting. But I will see what if I can do.

Goldsucher commented 1 year ago

Hi @mikebrady good news: The issue is gone. I don't know why, but now I don't have disconnects anymore. I think it's related to the update to macOS 13.

thanks for your help 🍻