2024-02-08 18:29:23 DEBUG [pyatv.scripts]: Running with pyatv 0.14.5
2024-02-08 18:29:23 DEBUG [pyatv.storage.file_storage]: Loading settings from /root/.pyatv.conf
2024-02-08 18:29:23 DEBUG [pyatv.support.net]: Binding on *:5353
2024-02-08 18:29:23 DEBUG [pyatv.support.net]: Binding on 127.0.0.1:0
2024-02-08 18:29:23 DEBUG [pyatv.support.net]: Binding on 192.168.0.59:0
2024-02-08 18:29:24 DEBUG [pyatv.core.scan]: Auto-discovered Samsung Soundbar S801B at 192.168.0.56:42575 via Protocol.AirPlay ({'acl': '0', 'deviceid': '80:8A:BD:16:B9:BB', 'features': '0x445F8A00,0x1C340', 'rsf': '0x0', 'fv': 'p20.SAT-iMX8M22WWC-1010.0', 'flags': '0x4', 'model': 'HW-S801B', 'manufacturer': 'Samsung', 'serialnumber': 'N/A', 'protovers': '1.1', 'srcvers': '366.0', 'pi': '80:8A:BD:16:B9:BB', 'gid': '80:8A:BD:16:B9:BB', 'gcgl': '0', 'pk': '49c82569244267a3e9aea37d8c5a7400426fb0d117564572b242898d31dcb9e6'})
2024-02-08 18:29:24 DEBUG [pyatv.core.scan]: Auto-discovered 808ABD16B9B8@Samsung Soundbar S801B at 192.168.0.56:42575 via Protocol.RAOP ({'cn': '0,1', 'da': 'true', 'et': '0,4', 'ft': '0x445F8A00,0x1C340', 'fv': 'p20.SAT-iMX8M22WWC-1010.0', 'md': '0,1,2', 'am': 'HW-S801B', 'sf': '0x4', 'tp': 'UDP', 'vn': '65537', 'vs': '366.0', 'pk': '49c82569244267a3e9aea37d8c5a7400426fb0d117564572b242898d31dcb9e6'})
2024-02-08 18:29:24 INFO [pyatv.scripts.atvremote]: Auto-discovered Samsung Soundbar S801B at 192.168.0.56
2024-02-08 18:29:24 DEBUG [pyatv]: Loading settings from FileStorage:/root/.pyatv.conf
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Adding handler for protocol Protocol.AirPlay
2024-02-08 18:29:24 DEBUG [pyatv.protocols.airplay]: Remote control not supported by device
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Adding handler for protocol Protocol.RAOP
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Connecting to protocol: Protocol.AirPlay
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Connected to protocol: Protocol.AirPlay
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Connecting to protocol: Protocol.RAOP
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Connected to protocol: Protocol.RAOP
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Power management not supported by any protocols
2024-02-08 18:29:24 DEBUG [pyatv.core.facade]: Takeover (<class 'pyatv.interface.Audio'>, <class 'pyatv.interface.Metadata'>, <class 'pyatv.interface.PushUpdater'>, <class 'pyatv.interface.RemoteControl'>) by Protocol.RAOP
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Connected to 192.168.0.56
2024-02-08 18:29:24 DEBUG [pyatv.protocols.raop]: Using AirPlay version AirPlayMajorVersion.AirPlayV2
2024-02-08 18:29:24 DEBUG [pyatv.protocols.raop.stream_client]: Initializing RTSP with encryption=9, metadata=7
2024-02-08 18:29:24 DEBUG [pyatv.protocols.raop.stream_client]: Update play settings to 44100/2/16bit
2024-02-08 18:29:24 DEBUG [pyatv.protocols.raop.stream_client]: Local ports: control=59958, timing=39288
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'GET /info RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nCSeq: 0\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\n\r\n'
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Received: b"RTSP/1.0 200 OK\r\nContent-Length: 564\r\nContent-Type: application/x-apple-binary-plist\r\nServer: AirTunes/366.0\r\nCSeq: 0\r\n\r\nbplist00\xdf\x10\x11\x01\x02\x03\x04\x05\x06\x07\x08\t\n\x0b\x0c\r\x0e\x0f\x10\x11\x12\x13\x14\x15\x16\x17\x18\x16\x16\x19\x13\x1a\x1b\x1c\x1d\x1e\x1fSsdkRpi_\x10\x10firmwareRevision\\manufacturer_\x10\x11keepAliveLowPower_\x10\x11firmwareBuildDateUmodel_\x10\x14nameIsFactoryDefault_\x10\x18keepAliveSendStatsAsBody[statusFlagsXdeviceIDUbuildWPTPInfo_\x10\x0fprotocolVersion]sourceVersionXfeaturesTname]AirPlay;2.0.7_\x10\x1180:8A:BD:16:B9:BB_\x10\x15SAT-iMX8M22WWC-1010.0WSamsung\t[Oct 24 2022XHW-S801B\x10\x04T47.0_\x10AOpenAVNU ArtAndLogic-aPTP-changes Commit: 17f0335 on Sep 22, 2018S1.1U366.0\x13\x00\x01\xc3@D_\x8a\x00_\x10\x16Samsung Soundbar S801B\x00\x08\x00-\x001\x004\x00G\x00T\x00h\x00|\x00\x82\x00\x99\x00\xb4\x00\xc0\x00\xc9\x00\xcf\x00\xd7\x00\xe9\x00\xf7\x01\x00\x01\x05\x01\x13\x01'\x01?\x01G\x01H\x01T\x01]\x01_\x01d\x01\xa8\x01\xac\x01\xb2\x01\xbb\x00\x00\x00\x00\x00\x00\x02\x01\x00\x00\x00\x00\x00\x00\x00 \x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x01\xd4"
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Content-Length': '564', 'Content-Type': 'application/x-apple-binary-plist', 'Server': 'AirTunes/366.0', 'CSeq': '0'}, body=b"bplist00\xdf\x10\x11\x01\x02\x03\x04\x05\x06\x07\x08\t\n\x0b\x0c\r\x0e\x0f\x10\x11\x12\x13\x14\x15\x16\x17\x18\x16\x16\x19\x13\x1a\x1b\x1c\x1d\x1e\x1fSsdkRpi_\x10\x10firmwareRevision\\manufacturer_\x10\x11keepAliveLowPower_\x10\x11firmwareBuildDateUmodel_\x10\x14nameIsFactoryDefault_\x10\x18keepAliveSendStatsAsBody[statusFlagsXdeviceIDUbuildWPTPInfo_\x10\x0fprotocolVersion]sourceVersionXfeaturesTname]AirPlay;2.0.7_\x10\x1180:8A:BD:16:B9:BB_\x10\x15SAT-iMX8M22WWC-1010.0WSamsung\t[Oct 24 2022XHW-S801B\x10\x04T47.0_\x10AOpenAVNU ArtAndLogic-aPTP-changes Commit: 17f0335 on Sep 22, 2018S1.1U366.0\x13\x00\x01\xc3@D_\x8a\x00_\x10\x16Samsung Soundbar S801B\x00\x08\x00-\x001\x004\x00G\x00T\x00h\x00|\x00\x82\x00\x99\x00\xb4\x00\xc0\x00\xc9\x00\xcf\x00\xd7\x00\xe9\x00\xf7\x01\x00\x01\x05\x01\x13\x01'\x01?\x01G\x01H\x01T\x01]\x01_\x01d\x01\xa8\x01\xac\x01\xb2\x01\xbb\x00\x00\x00\x00\x00\x00\x02\x01\x00\x00\x00\x00\x00\x00\x00 \x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x01\xd4"):
2024-02-08 18:29:24 DEBUG [pyatv.protocols.raop.stream_client]: Updated info parameters to: {'sdk': 'AirPlay;2.0.7', 'pi': '80:8A:BD:16:B9:BB', 'firmwareRevision': 'SAT-iMX8M22WWC-1010.0', 'manufacturer': 'Samsung', 'keepAliveLowPower': True, 'firmwareBuildDate': 'Oct 24 2022', 'model': 'HW-S801B', 'nameIsFactoryDefault': True, 'keepAliveSendStatsAsBody': True, 'statusFlags': 4, 'deviceID': '80:8A:BD:16:B9:BB', 'build': '47.0', 'PTPInfo': 'OpenAVNU ArtAndLogic-aPTP-changes Commit: 17f0335 on Sep 22, 2018', 'protocolVersion': '1.1', 'sourceVersion': '366.0', 'features': 496155769145856, 'name': 'Samsung Soundbar S801B'}
2024-02-08 18:29:24 DEBUG [pyatv.protocols.airplay.auth]: Setting up new AirPlay Pair-Verify procedure with type AuthenticationType.Transient
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Sending HTTP/1.1 message: b'POST /pair-pin-start HTTP/1.1\r\nUser-Agent: AirPlay/320.20\r\nConnection: keep-alive\r\nX-Apple-HKP: 4\r\nContent-Type: application/octet-stream\r\n\r\n'
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Received: b'HTTP/1.1 200 OK\r\nServer: AirTunes/366.0\r\n\r\n'
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Got HTTP response: HttpResponse(protocol='HTTP', version='1.1', code=200, message='OK', headers={'Server': 'AirTunes/366.0'}, body=''):
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Sending HTTP/1.1 message: b'POST /pair-setup HTTP/1.1\r\nContent-Length: 9\r\nUser-Agent: AirPlay/320.20\r\nConnection: keep-alive\r\nX-Apple-HKP: 4\r\nContent-Type: application/octet-stream\r\n\r\n\x00\x01\x00\x06\x01\x01\x13\x01\x10'
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Received: b'HTTP/1.1 200 OK\r\nContent-Length: 409\r\nContent-Type: application/octet-stream\r\nServer: AirTunes/366.0\r\n\r\n\x06\x01\x02\x02\x10o\x15?\xe1\xa3F\x01eM\xd8\x0cy\xc2\xb1\xec\xa6\x03\xffn\xb4\xb3\xf7\x00}S6\x856]\xb0\xaaY\xbe\xecp\x91Mc\xc2\x17\x94\xc4qE\xde\t7\xd7]gQvE\xc7\t\xc2\xa5\xc9\x06 $Q\x87\x02s\x1e+\xdc/\\&\xe6"\xabL\x08\x03\xc3\xae\x82\x90\x15%\xf8\x1a\xc1/\xfcK\xb6\xf9\xa0\xb7g\x1f\x0eD~O\xd1Q\xba[e!(\x8b\xd1f*]\x940\xff\xb1\xac\rO\xaa\xc2X}\xf4\x9av#\x9f\xac\x8a>d\xa8\x01\xb3=\x96\xda@\xcb0^\xb6\x8c\xd0\x8d"\x0e\x93p4\xf5\x898\xa7\x89\xeb\x8fNkBj\xa0\xfdmf~=\x01\xcf\xcd\xc2\xa7y\xcb\x8f\xd7\xb1\xd3\\R\xdcQ^\x80y\xc8p\xa8\x9b!\xf1\x81&Y\xfdJG\xc02\x1awI\x98|\x16\xc1\xd7\xc3A\xbf\xe4\x8022s\xce\x955\x87?MR\xfd*HZ\xd0\xb63\xe6U\xe6\xec\xaf\xcd\xb4\x147\x9bj\x98\xa5\x9a\xec\xf9\xde\xedc\xe4\xc0C\x16\xbbSsp&\';H\x90F\xf7\x04uFw\xc2\xfe\x97Z \x1a\x03\x81\xed)\x93\xdd^\xcc\xb2\xcew\x01b\xd1Y\xdd\x99\xb6\xea\xf1\xf7#\x9f\x0f\x10e(5\xbb\xd3d\x1bBU\xd0\xfa\x89\xc5\x0c\x03\x02j\xeb\xc2\xb9@\x946a`[R\xfcF\xe1#\xach5\xe2\xa3\xab4\xb7*\xad\xa7\x1a\x8b\xc6\x00\xee\x1a\x01\xd5l\xb2\xb2\xf0\x8a\xebR\\\x9e1@\x98u_\x80\x17\xc0\xde\xd5\xc1I\xd4\x8f\xb1\xeb<\xa9o\xdb@\x95\xb6sx*\x03\x12\xb4\xb5\xb9\'\xd9\x03\xb2\xbeV\xcb\x0b!\xc6o\xff\xf8\xdeDc'
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Got HTTP response: HttpResponse(protocol='HTTP', version='1.1', code=200, message='OK', headers={'Content-Length': '409', 'Content-Type': 'application/octet-stream', 'Server': 'AirTunes/366.0'}, body=b'\x06\x01\x02\x02\x10o\x15?\xe1\xa3F\x01eM\xd8\x0cy\xc2\xb1\xec\xa6\x03\xffn\xb4\xb3\xf7\x00}S6\x856]\xb0\xaaY\xbe\xecp\x91Mc\xc2\x17\x94\xc4qE\xde\t7\xd7]gQvE\xc7\t\xc2\xa5\xc9\x06 $Q\x87\x02s\x1e+\xdc/\\&\xe6"\xabL\x08\x03\xc3\xae\x82\x90\x15%\xf8\x1a\xc1/\xfcK\xb6\xf9\xa0\xb7g\x1f\x0eD~O\xd1Q\xba[e!(\x8b\xd1f*]\x940\xff\xb1\xac\rO\xaa\xc2X}\xf4\x9av#\x9f\xac\x8a>d\xa8\x01\xb3=\x96\xda@\xcb0^\xb6\x8c\xd0\x8d"\x0e\x93p4\xf5\x898\xa7\x89\xeb\x8fNkBj\xa0\xfdmf~=\x01\xcf\xcd\xc2\xa7y\xcb\x8f\xd7\xb1\xd3\\R\xdcQ^\x80y\xc8p\xa8\x9b!\xf1\x81&Y\xfdJG\xc02\x1awI\x98|\x16\xc1\xd7\xc3A\xbf\xe4\x8022s\xce\x955\x87?MR\xfd*HZ\xd0\xb63\xe6U\xe6\xec\xaf\xcd\xb4\x147\x9bj\x98\xa5\x9a\xec\xf9\xde\xedc\xe4\xc0C\x16\xbbSsp&\';H\x90F\xf7\x04uFw\xc2\xfe\x97Z \x1a\x03\x81\xed)\x93\xdd^\xcc\xb2\xcew\x01b\xd1Y\xdd\x99\xb6\xea\xf1\xf7#\x9f\x0f\x10e(5\xbb\xd3d\x1bBU\xd0\xfa\x89\xc5\x0c\x03\x02j\xeb\xc2\xb9@\x946a`[R\xfcF\xe1#\xach5\xe2\xa3\xab4\xb7*\xad\xa7\x1a\x8b\xc6\x00\xee\x1a\x01\xd5l\xb2\xb2\xf0\x8a\xebR\\\x9e1@\x98u_\x80\x17\xc0\xde\xd5\xc1I\xd4\x8f\xb1\xeb<\xa9o\xdb@\x95\xb6sx*\x03\x12\xb4\xb5\xb9\'\xd9\x03\xb2\xbeV\xcb\x0b!\xc6o\xff\xf8\xdeDc'):
2024-02-08 18:29:24 DEBUG [pyatv.auth.hap_srp]: Client (Proof=c92c2314ccd3cff351987b36fa7be6ddad769ad5cfa166fa35c521211c6de52d65abbd2357f957fb5ce90fcac6074bf1bc21ff13bd5291a129bad7e2b0ac5c72, Public=a77135fe035d6524f2061dd6b7e55b9f977fb75154f6859e0c1a024df54c5317f22ef3029f3bd008533f64721b3e13862510af8be6bb855215dcff4ad403964a922ad7fbe51d509030d27c5f491b89e32d8504c151f664a089fe3881074757bbab4521d4258abeec074b00783293fb12ac7e30cffdfa4108d9d723106c459fc8427ecc9880b560340bd76d2cbf44113a2de1b932e8df04b6070e4437d21e347080d6b0fcaed97d0611d9cf5b4317832396a60969625ea4cba7ea61af1c632a52b1d22393fd7c1cda7f559b5cf831dffdca91601a1889f2718b414ac8f86dfdfae149b38d4bd7592fbb0236c031b1381bafb04c21c54473a83da9553150854...)
2024-02-08 18:29:24 DEBUG [pyatv.support.http]: Sending HTTP/1.1 message: b'POST /pair-setup HTTP/1.1\r\nContent-Length: 457\r\nUser-Agent: AirPlay/320.20\r\nConnection: keep-alive\r\nX-Apple-HKP: 4\r\nContent-Type: application/octet-stream\r\n\r\n\x06\x01\x03\x03\xff\xa7q5\xfe\x03]e$\xf2\x06\x1d\xd6\xb7\xe5[\x9f\x97\x7f\xb7QT\xf6\x85\x9e\x0c\x1a\x02M\xf5LS\x17\xf2.\xf3\x02\x9f;\xd0\x08S?dr\x1b>\x13\x86%\x10\xaf\x8b\xe6\xbb\x85R\x15\xdc\xffJ\xd4\x03\x96J\x92*\xd7\xfb\xe5\x1dP\x900\xd2|_I\x1b\x89\xe3-\x85\x04\xc1Q\xf6d\xa0\x89\xfe8\x81\x07GW\xbb\xabE!\xd4%\x8a\xbe\xec\x07K\x00x2\x93\xfb\x12\xac~0\xcf\xfd\xfaA\x08\xd9\xd7#\x10lE\x9f\xc8B~\xcc\x98\x80\xb5`4\x0b\xd7m,\xbfD\x11:-\xe1\xb92\xe8\xdf\x04\xb6\x07\x0eD7\xd2\x1e4p\x80\xd6\xb0\xfc\xae\xd9}\x06\x11\xd9\xcf[C\x17\x83#\x96\xa6\tib^\xa4\xcb\xa7\xeaa\xaf\x1cc*R\xb1\xd2#\x93\xfd|\x1c\xda\x7fU\x9b\\\xf81\xdf\xfd\xca\x91`\x1a\x18\x89\xf2q\x8bAJ\xc8\xf8m\xfd\xfa\xe1I\xb3\x8dK\xd7Y/\xbb\x026\xc01\xb18\x1b\xaf\xb0L!\xc5Ds\xa8=\xa9U1P\x85A\x03\x81\xc3\xd5\xb62m++\x91\xcfo;\xd9\xe2MC\xc4=^\xe7\x86\xd73\x8f\x10j\x1e\xdd\xa2:W\xcel\x80\xfb\t{\xda&\x93\x07\xe9\x8a\xae\x02K\x0f\x97J\n\xdc\x02R\xdc\n#J\xf9\xbfO$\xf7\xb7P;\x90\xeb\x83]\xe6\xea8\x94\xdep\xd6l\x8c\xd3-R\xcd"\xdb\xc2x\xdd\x8c\x02\x80o\xfc\xe8#\xf2\xcc3\xbcne?\xbd9\r\x82\xca0\xd5\x17\xa2\xc7\'\x92Q\xf07\x81,\xb4\xbe>K\x9f\xbemX\xc2\xb6;\xda\x04@\xc9,#\x14\xcc\xd3\xcf\xf3Q\x98{6\xfa{\xe6\xdd\xadv\x9a\xd5\xcf\xa1f\xfa5\xc5!!\x1cm\xe5-e\xab\xbd#W\xf9W\xfb\\\xe9\x0f\xca\xc6\x07K\xf1\xbc!\xff\x13\xbdR\x91\xa1)\xba\xd7\xe2\xb0\xac\\r'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b"HTTP/1.1 200 OK\r\nContent-Length: 69\r\nContent-Type: application/octet-stream\r\nServer: AirTunes/366.0\r\n\r\n\x06\x01\x04\x04@1\xd8)\xc4\xcc\x0cLa\x9a\x91:\xa5\x1e\x03mB\xaeq{\xae\xbb\xbd:f\xd2\xe6\xbb\xea\xd8\x8c\x8f\xaba\xde\x96\xe4\x90CS\x91\xf4\xe4s\x05/3\xca\x12K\xa1\xdf\xfd@l\xdeH}S'\xeeOH\xcf\x89"
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got HTTP response: HttpResponse(protocol='HTTP', version='1.1', code=200, message='OK', headers={'Content-Length': '69', 'Content-Type': 'application/octet-stream', 'Server': 'AirTunes/366.0'}, body=b"\x06\x01\x04\x04@1\xd8)\xc4\xcc\x0cLa\x9a\x91:\xa5\x1e\x03mB\xaeq{\xae\xbb\xbd:f\xd2\xe6\xbb\xea\xd8\x8c\x8f\xaba\xde\x96\xe4\x90CS\x91\xf4\xe4s\x05/3\xca\x12K\xa1\xdf\xfd@l\xdeH}S'\xeeOH\xcf\x89"):
2024-02-08 18:29:25 DEBUG [pyatv.protocols.airplay.auth.hap_transient]: Keys (Input=37a336dd6f6557aac5c0044f5fa304f729a906cab1362dc7499f4f80e0e00375, Output=c6afd1278f466a0b4d1ee19dc1131ddba29853845d6d22e3564b5640003cb49b)
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'SETUP rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nContent-Length: 455\r\nCSeq: 1\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\nContent-Type: application/x-apple-binary-plist\r\n\r\nbplist00\xdf\x10\x0f\x01\x02\x03\x04\x05\x06\x07\x08\t\n\x0b\x0c\r\x0e\x0f\x10\x11\x12\x10\x13\x14\x15\x16\x17\x11\x18\x19\x11\x1a\x1bXdeviceID_\x10\x18groupContainsGroupLeader_\x10\x14isMultiSelectAirPlayZmacAddressUmodelTname^osBuildVersionVosNameYosVersion_\x10\x13senderSupportsRelay[sessionUUID]sourceVersion_\x10\x16statsCollectionEnabledZtimingPort^timingProtocol_\x10\x11AA:BB:CC:DD:EE:FF\x08\tZiPhone14,3UpyatvU20F66YiPhone OST16.5_\x10$669B66C2-DD7A-491A-A552-4994F9217651W690.7.1\x11\x99xSNTP\x00\x08\x00)\x002\x00M\x00d\x00o\x00u\x00z\x00\x89\x00\x90\x00\x9a\x00\xb0\x00\xbc\x00\xca\x00\xe3\x00\xee\x00\xfd\x01\x11\x01\x12\x01\x13\x01\x1e\x01$\x01*\x014\x019\x01`\x01h\x01k\x00\x00\x00\x00\x00\x00\x02\x01\x00\x00\x00\x00\x00\x00\x00\x1c\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x01o'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nContent-Length: 77\r\nContent-Type: application/x-apple-binary-plist\r\nServer: AirTunes/366.0\r\nCSeq: 1\r\n\r\nbplist00\xd2\x01\x02\x03\x04YeventPortZtimingPort\x11\xae\x19\x11\xd3\xf1\x08\r\x17"%\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x05\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00('
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Content-Length': '77', 'Content-Type': 'application/x-apple-binary-plist', 'Server': 'AirTunes/366.0', 'CSeq': '1'}, body=b'bplist00\xd2\x01\x02\x03\x04YeventPortZtimingPort\x11\xae\x19\x11\xd3\xf1\x08\r\x17"%\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x05\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00('):
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.protocols.airplayv2]: Setup response body: {'eventPort': 44569, 'timingPort': 54257}
2024-02-08 18:29:25 DEBUG [pyatv.protocols.airplay.auth.hap_transient]: Keys (Input=f81089d11fb987d97ef42c208a95bf03b7e8dee9ccc805dd5effaac88fb43513, Output=949882d1809e4d9c501e89ef93409bea7a5086b399d35d8f720f6950c494eb8b)
2024-02-08 18:29:25 DEBUG [pyatv.auth.hap_channel]: Connected to 192.168.0.56:44569
2024-02-08 18:29:25 DEBUG [pyatv.protocols.airplay.auth.hap_transient]: Keys (Input=949882d1809e4d9c501e89ef93409bea7a5086b399d35d8f720f6950c494eb8b, Output=f81089d11fb987d97ef42c208a95bf03b7e8dee9ccc805dd5effaac88fb43513)
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'SETUP rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nContent-Length: 314\r\nCSeq: 2\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\nContent-Type: application/x-apple-binary-plist\r\n\r\nbplist00\xd1\x01\x02Wstreams\xa1\x03\xdd\x04\x05\x06\x07\x08\t\n\x0b\x0c\r\x0e\x0f\x10\x11\x12\x13\x14\x15\x16\x17\x18\x19\x1a\x1b\x1c\x1d[audioFormatYaudioMode[controlPortRctWisMediaZlatencyMaxZlatencyMinSshkSspfRsr_\x10\x12streamConnectionID_\x10\x17supportsDynamicStreamIDTtype\x11\x08\x00Wdefault\x11\xea6\x10\x01\t\x12\x00\x01X\x88\x11+\x11O\x10 \xf8\x10\x89\xd1\x1f\xb9\x87\xd9~\xf4, \x8a\x95\xbf\x03\xb7\xe8\xde\xe9\xcc\xc8\x05\xdd^\xff\xaa\xc8\x8f\xb45\x13\x11\x01`\x11\xacD\x12\xa4Q\xe9K\x08\x10`\x08\x0b\x13\x150<FRU]hsw{~\x93\xad\xb2\xb5\xbd\xc0\xc2\xc3\xc8\xcb\xee\xf1\xf4\xf9\xfa\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x1e\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\xfc'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nContent-Length: 104\r\nContent-Type: application/x-apple-binary-plist\r\nServer: AirTunes/366.0\r\nCSeq: 2\r\n\r\nbplist00\xd1\x01\x02Wstreams\xa1\x03\xd3\x04\x05\x06\x07\x08\tTtypeXdataPort[controlPort\x10`\x11\xa6Y\x11\xd1\xe0\x08\x0b\x13\x15\x1c!*68;\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\n\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00>'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Content-Length': '104', 'Content-Type': 'application/x-apple-binary-plist', 'Server': 'AirTunes/366.0', 'CSeq': '2'}, body=b'bplist00\xd1\x01\x02Wstreams\xa1\x03\xd3\x04\x05\x06\x07\x08\tTtypeXdataPort[controlPort\x10`\x11\xa6Y\x11\xd1\xe0\x08\x0b\x13\x15\x1c!*68;\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\n\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00>'):
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.protocols.airplayv2]: Setup stream response: {'streams': [{'type': 96, 'dataPort': 42585, 'controlPort': 53728}]}
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'SET_PARAMETER rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nContent-Type: text/parameters\r\nContent-Length: 13\r\nCSeq: 3\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\n\r\nvolume: -20.1'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nServer: AirTunes/366.0\r\nCSeq: 3\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Server': 'AirTunes/366.0', 'CSeq': '3'}, body=''):
2024-02-08 18:29:25 DEBUG [pyatv.core.protocol]: Dispatching message with type UpdatedState.Volume to <bound method FacadeAudio._volume_changed of <pyatv.core.facade.FacadeAudio object at 0x7fd114a62fd0>>
2024-02-08 18:29:25 DEBUG [pyatv.core.protocol]: Dispatching message with type UpdatedState.Volume to <bound method RaopAudio._volume_changed of <pyatv.protocols.raop.RaopAudio object at 0x7fd114a3bc50>>
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop]: Protocol RAOP changed volume to 33.000000
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.stream_client]: Starting periodic sync task
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'SET_PARAMETER rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nContent-Type: text/parameters\r\nContent-Length: 28\r\nCSeq: 4\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\n\r\nprogress: 66150/66150/242550'
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.stream_client]: Sending sync packet (Frac=1826160640, RtpTime=66150, Sec=3916423765, SyncPacket=90d4000700000000e96fe2556cd9000000010266)
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nServer: AirTunes/366.0\r\nCSeq: 4\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Server': 'AirTunes/366.0', 'CSeq': '4'}, body=''):
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.stream_client]: Playing with metadata: MediaMetadata(title=None, artist=None, album=None, artwork=None, duration=3.96827664399093)
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'SET_PARAMETER rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nContent-Type: application/x-dmap-tagged\r\nContent-Length: 8\r\nCSeq: 5\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\nSession: 0\r\nRTP-Info: seq=18071;rtptime=66150\r\n\r\nmlit\x00\x00\x00\x00'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nServer: AirTunes/366.0\r\nCSeq: 5\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Server': 'AirTunes/366.0', 'CSeq': '5'}, body=''):
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'RECORD rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nCSeq: 6\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.protocols.airplayv2]: Starting feedback task
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'POST /feedback RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nCSeq: 7\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nServer: AirTunes/366.0\r\nCSeq: 6\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Server': 'AirTunes/366.0', 'CSeq': '6'}, body=''):
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'FLUSH rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nCSeq: 8\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\nRange: npt=0-\r\nSession: 0\r\nRTP-Info: seq=18071;rtptime=66150\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nContent-Length: 85\r\nContent-Type: application/x-apple-binary-plist\r\nServer: AirTunes/366.0\r\nCSeq: 7\r\n\r\nbplist00\xd1\x01\x02Wstreams\xa1\x03\xd2\x04\x05\x06\x07TtypeRsr\x10`#@\xe5\x88\x80\x00\x00\x00\x00\x08\x0b\x13\x15\x1a\x1f"$\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00-'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Content-Length': '85', 'Content-Type': 'application/x-apple-binary-plist', 'Server': 'AirTunes/366.0', 'CSeq': '7'}, body=b'bplist00\xd1\x01\x02Wstreams\xa1\x03\xd2\x04\x05\x06\x07TtypeRsr\x10`#@\xe5\x88\x80\x00\x00\x00\x00\x08\x0b\x13\x15\x1a\x1f"$\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00-'):
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nRTP-Info: rtptime=0\r\nServer: AirTunes/366.0\r\nCSeq: 8\r\n\r\n'
2024-02-08 18:29:25 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'RTP-Info': 'rtptime=0', 'Server': 'AirTunes/366.0', 'CSeq': '8'}, body=''):
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.stream_client]: Too slow to keep up for seqno 18107 (0.303311 vs 0.307252 => -0.003941)
2024-02-08 18:29:25 DEBUG [pyatv.protocols.raop.stream_client]: Too slow to keep up for seqno 18109 (0.319274 vs 0.321266 => -0.001992)
2024-02-08 18:29:26 DEBUG [pyatv.protocols.raop.stream_client]: Sending sync packet (Frac=1370750976, RtpTime=105574, Sec=3916423766, SyncPacket=80d4000700009a00e96fe25651b4000000019c66)
2024-02-08 18:29:26 DEBUG [pyatv.protocols.raop.stream_client]: Sent 44352 frames in 0.998922s (current frames: 44352, expected: 44054)
2024-02-08 18:29:27 DEBUG [pyatv.protocols.raop.stream_client]: Sending sync packet (Frac=1360986112, RtpTime=149574, Sec=3916423767, SyncPacket=80d40007000145e0e96fe257511f000000024846)
2024-02-08 18:29:27 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'POST /feedback RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nCSeq: 9\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\n\r\n'
2024-02-08 18:29:27 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nContent-Length: 85\r\nContent-Type: application/x-apple-binary-plist\r\nServer: AirTunes/366.0\r\nCSeq: 9\r\n\r\nbplist00\xd1\x01\x02Wstreams\xa1\x03\xd2\x04\x05\x06\x07TtypeRsr\x10`#@\xe5\x88\x80\x00\x00\x00\x00\x08\x0b\x13\x15\x1a\x1f"$\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00-'
2024-02-08 18:29:27 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Content-Length': '85', 'Content-Type': 'application/x-apple-binary-plist', 'Server': 'AirTunes/366.0', 'CSeq': '9'}, body=b'bplist00\xd1\x01\x02Wstreams\xa1\x03\xd2\x04\x05\x06\x07TtypeRsr\x10`#@\xe5\x88\x80\x00\x00\x00\x00\x08\x0b\x13\x15\x1a\x1f"$\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00-'):
2024-02-08 18:29:27 DEBUG [pyatv.protocols.raop.stream_client]: Sent 44352 frames in 1.005831s (current frames: 88704, expected: 88409)
2024-02-08 18:29:28 DEBUG [pyatv.protocols.raop.stream_client]: Sending sync packet (Frac=1385562112, RtpTime=193926, Sec=3916423768, SyncPacket=80d400070001f320e96fe258529600000002f586)
2024-02-08 18:29:28 DEBUG [pyatv.protocols.raop.stream_client]: Sent 44352 frames in 1.006240s (current frames: 133056, expected: 132785)
2024-02-08 18:29:29 DEBUG [pyatv.protocols.raop.stream_client]: Sending sync packet (Frac=1375797248, RtpTime=237926, Sec=3916423769, SyncPacket=80d4000700029f00e96fe259520100000003a166)
2024-02-08 18:29:29 DEBUG [pyatv.protocols.raop.stream_client]: Too slow to keep up for seqno 18558 (3.903129 vs 3.905802 => -0.002673)
2024-02-08 18:29:29 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'POST /feedback RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nCSeq: 10\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\n\r\n'
2024-02-08 18:29:29 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nContent-Length: 85\r\nContent-Type: application/x-apple-binary-plist\r\nServer: AirTunes/366.0\r\nCSeq: 10\r\n\r\nbplist00\xd1\x01\x02Wstreams\xa1\x03\xd2\x04\x05\x06\x07TtypeRsr\x10`#@\xe5\x88\x80\x00\x00\x00\x00\x08\x0b\x13\x15\x1a\x1f"$\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00-'
2024-02-08 18:29:29 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Content-Length': '85', 'Content-Type': 'application/x-apple-binary-plist', 'Server': 'AirTunes/366.0', 'CSeq': '10'}, body=b'bplist00\xd1\x01\x02Wstreams\xa1\x03\xd2\x04\x05\x06\x07TtypeRsr\x10`#@\xe5\x88\x80\x00\x00\x00\x00\x08\x0b\x13\x15\x1a\x1f"$\x00\x00\x00\x00\x00\x00\x01\x01\x00\x00\x00\x00\x00\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00-'):
2024-02-08 18:29:29 DEBUG [pyatv.protocols.raop.stream_client]: Sent 44352 frames in 1.004778s (current frames: 177408, expected: 177095)
2024-02-08 18:29:30 DEBUG [pyatv.protocols.raop.stream_client]: Too slow to keep up for seqno 18629 (4.469841 vs 4.470101 => -0.000260)
2024-02-08 18:29:30 DEBUG [pyatv.protocols.raop.stream_client]: Sending sync packet (Frac=1434648576, RtpTime=282630, Sec=3916423770, SyncPacket=80d4000700034da0e96fe25a5583000000045006)
2024-02-08 18:29:30 DEBUG [pyatv.protocols.raop.stream_client]: Sent 44352 frames in 1.005359s (current frames: 221760, expected: 221432)
2024-02-08 18:29:31 DEBUG [pyatv.protocols.raop.stream_client]: Audio finished sending in 5.524585s
2024-02-08 18:29:31 DEBUG [pyatv.support.http]: Sending RTSP/1.0 message: b'TEARDOWN rtsp://192.168.0.59/2756831563 RTSP/1.0\r\nUser-Agent: AirPlay/550.10\r\nCSeq: 11\r\nDACP-ID: 2AC78E1AD3DB3776\r\nActive-Remote: 174360655\r\nClient-Instance: 2AC78E1AD3DB3776\r\nSession: 0\r\n\r\n'
2024-02-08 18:29:31 DEBUG [pyatv.auth.hap_channel]: Connection was lost to remote
2024-02-08 18:29:31 DEBUG [pyatv.support.http]: Received: b'RTSP/1.0 200 OK\r\nServer: AirTunes/366.0\r\nCSeq: 11\r\n\r\n'
2024-02-08 18:29:31 DEBUG [pyatv.support.http]: Got RTSP response: HttpResponse(protocol='RTSP', version='1.0', code=200, message='OK', headers={'Server': 'AirTunes/366.0', 'CSeq': '11'}, body=''):
2024-02-08 18:29:31 DEBUG [pyatv.core.facade]: Release (<class 'pyatv.interface.Audio'>, <class 'pyatv.interface.Metadata'>, <class 'pyatv.interface.PushUpdater'>, <class 'pyatv.interface.RemoteControl'>) by Protocol.RAOP
2024-02-08 18:29:31 DEBUG [pyatv.scripts.atvremote]: Waiting for 0 remaining tasks
2024-02-08 18:29:31 DEBUG [pyatv.protocols.raop.stream_client]: Audio connection lost (None)
2024-02-08 18:29:31 DEBUG [pyatv.protocols.raop.stream_client]: Control connection lost (None)
2024-02-08 18:29:31 DEBUG [pyatv.support.http]: Connection closed
2024-02-08 18:29:31 DEBUG [pyatv.protocols.raop.stream_client]: Periodic sync task ended