hassio-addons / addon-aircast

AirCast - Home Assistant Community Add-ons
https://addons.community
MIT License
314 stars 18 forks source link

Fails to start playback - Home Assistant 6.4 - Buffer overrun? #105

Closed Aviatorpaal closed 2 years ago

Aviatorpaal commented 2 years ago

Problem/Motivation

First time trying this awesome addon (thanks, already!) music fails to playback on Chromecast, even though setup looks fine, all devices find and interact with each other.

Expected behavior

Unable to playback music, Chromecast opens streaming app

Actual behavior

Streaming appears to start (on both sides (iPhone and Chromecast), no music playback.

Logs: loglastairplay loglastairplay2 loglastairplay3 loglastairplay4

With buffer overrun several times. Sorry for no text logs, this was captured with the server and client 2000km away, unable to run the tests again at the moment.

Steps to reproduce

Home Assistant 6.4 as a VM in ESXi 6.5, pfSense firewall with Avahi and firewall rules configured

Proposed changes

I will gladly help debugging.

fservida commented 2 years ago

Had the same problem, in my case it was solved by configuring a 1000ms latency in the settings for the HTTP Latency image

YMMV but hopefully it's useful.

Aviatorpaal commented 2 years ago

Thank you for your reply! I have tried what you specified, but with no success. Please see the attached logs:

logg.txt

Aviatorpaal commented 2 years ago

`[16:54:29.056025] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:1021] [W:48591 R:47571] [16:54:29.063355] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:29.593636] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:29.978442] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215247902 rtp:3215325077 remote ntp:83ad385267f316e3, local time 1554252262 (now:78921603) [16:54:30.107929] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:30.629615] http_parse:1147 sock: 8, received CSeq: 29 [16:54:30.629715] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:30.629783] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:30.629852] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:30.638331] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:31.036755] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215292883 rtp:3215370058 remote ntp:83ad38536d110c2c, local time 1554253282 (now:78922662) [16:54:31.168487] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1031 [16:54:31.683185] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:32.074501] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215337869 rtp:3215415044 remote ntp:83ad38547234d6a1, local time 1554254302 (now:78923699) [16:54:32.074538] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78923699 (port: 60976) [16:54:32.213789] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:32.603397] http_parse:1147 sock: 8, received CSeq: 30 [16:54:32.604021] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:32.604100] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:32.604379] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:32.744069] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:33.019211] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215382850 rtp:3215460025 remote ntp:83ad3855775271bc, local time 1554255322 (now:78924644) [16:54:33.122960] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:1006] [W:49103 R:48098] [16:54:33.258088] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:33.788261] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:34.039423] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215427836 rtp:3215505011 remote ntp:83ad38567c779852, local time 1554256342 (now:78925664) [16:54:34.302761] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:34.580238] http_parse:1147 sock: 8, received CSeq: 31 [16:54:34.580369] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:34.580465] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:34.580566] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:34.832915] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:35.058764] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215472815 rtp:3215549990 remote ntp:83ad38578190a99b, local time 1554257362 (now:78926684) [16:54:35.347403] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:35.877849] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:36.078579] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215517794 rtp:3215594969 remote ntp:83ad385886ab777d, local time 1554258382 (now:78927703) [16:54:36.078621] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78927703 (port: 60976) [16:54:36.408468] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:36.589853] http_parse:1147 sock: 8, received CSeq: 32 [16:54:36.589940] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:36.590029] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:36.590101] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:36.922804] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1030 [16:54:37.098850] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215562775 rtp:3215639950 remote ntp:83ad38598bc92844, local time 1554259402 (now:78928724) [16:54:37.189314] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:992] [W:49615 R:48624] [16:54:37.453382] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:37.983850] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:38.119023] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215607760 rtp:3215684935 remote ntp:83ad385a90eba8c5, local time 1554260422 (now:78929744) [16:54:38.497882] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:38.612545] http_parse:1147 sock: 8, received CSeq: 33 [16:54:38.612637] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:38.612707] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:38.612775] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:39.028557] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:39.138777] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215652739 rtp:3215729914 remote ntp:83ad385b96069f32, local time 1554261442 (now:78930764) [16:54:39.559511] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1026 [16:54:40.073762] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1027 [16:54:40.158668] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215697722 rtp:3215774897 remote ntp:83ad385c9b2617c1, local time 1554262462 (now:78931783) [16:54:40.158691] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78931783 (port: 60976) [16:54:40.603682] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:40.639969] http_parse:1147 sock: 8, received CSeq: 34 [16:54:40.640090] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:40.640378] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:40.640457] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:41.118704] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:41.178724] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215742707 rtp:3215819882 remote ntp:83ad385da04a281e, local time 1554263482 (now:78932803) [16:54:41.268946] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:983] [W:50127 R:49145] [16:54:41.649001] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:42.179244] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1031 [16:54:42.198933] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215787686 rtp:3215864861 remote ntp:83ad385ea5648626, local time 1554264502 (now:78933824) [16:54:42.682957] http_parse:1147 sock: 8, received CSeq: 35 [16:54:42.683061] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:42.683126] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:42.683188] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:42.693731] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:43.219280] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215832668 rtp:3215909843 remote ntp:83ad385faa82eec7, local time 1554265522 (now:78934844) [16:54:43.224005] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:43.738592] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:44.256856] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215877650 rtp:3215954825 remote ntp:83ad3860afa1e81c, local time 1554266542 (now:78935882) [16:54:44.256891] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78935882 (port: 60976) [16:54:44.270055] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:54:44.660384] http_parse:1147 sock: 8, received CSeq: 36 [16:54:44.660483] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:44.660569] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:44.660662] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:44.784576] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:45.309423] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215922632 rtp:3215999807 remote ntp:83ad3861b4bfd71b, local time 1554267562 (now:78936934) [16:54:45.314809] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:45.350333] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:968] [W:50639 R:49672] [16:54:45.844922] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1027 [16:54:46.353797] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3215967613 rtp:3216044788 remote ntp:83ad3862b9ddbfce, local time 1554268582 (now:78937979) [16:54:46.358770] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1026 [16:54:46.752237] http_parse:1147 sock: 8, received CSeq: 37 [16:54:46.752325] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:54:46.752390] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:54:46.752457] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:54:46.888763] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1030 [16:54:47.298817] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3216012598 rtp:3216089773 remote ntp:83ad3863beffc813, local time 1554269602 (now:78938924) [16:54:47.402875] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:54:47.932846] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028


[16:55:02.810486] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:02.810547] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:03.140484] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:03.649764] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3216732308 rtp:3216809483 remote ntp:83ad387410e89a88, local time 1554285922 (now:78955275) [16:55:03.654825] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:04.185178] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:04.698047] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3216777291 rtp:3216854466 remote ntp:83ad38751609bf9c, local time 1554286942 (now:78956323) [16:55:04.698110] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78956323 (port: 60976) [16:55:04.699150] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1026 [16:55:04.858066] http_parse:1147 sock: 8, received CSeq: 46 [16:55:04.858352] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:04.858531] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:04.858700] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:05.229368] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1027 [16:55:05.746522] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3216822273 rtp:3216899448 remote ntp:83ad38761b27c22d, local time 1554287962 (now:78957371) [16:55:05.759955] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:05.780241] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:965] [W:53199 R:52235] [16:55:06.290225] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:06.678372] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3216867252 rtp:3216944427 remote ntp:83ad38772042dadb, local time 1554288982 (now:78958303) [16:55:06.804589] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:06.862194] http_parse:1147 sock: 8, received CSeq: 47 [16:55:06.862527] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:06.862619] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:06.862877] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:07.335206] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:07.717566] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3216912235 rtp:3216989410 remote ntp:83ad3878256310db, local time 1554290002 (now:78959342) [16:55:07.849611] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:08.379845] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1030 [16:55:08.765549] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3216957221 rtp:3217034396 remote ntp:83ad38792a8653b3, local time 1554291022 (now:78960390) [16:55:08.765583] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78960390 (port: 60976) [16:55:08.891512] http_parse:1147 sock: 8, received CSeq: 48 [16:55:08.891602] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:08.891669] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:08.891744] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:08.894409] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:09.424514] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:09.814515] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217002201 rtp:3217079376 remote ntp:83ad387a2fa240e4, local time 1554292042 (now:78961439) [16:55:09.939789] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:1018] [W:53711 R:52694] [16:55:09.955234] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:10.469388] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:10.758590] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217047183 rtp:3217124358 remote ntp:83ad387b34c128bf, local time 1554293062 (now:78962383) [16:55:10.880012] http_parse:1147 sock: 8, received CSeq: 49 [16:55:10.880114] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:10.880201] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:10.880671] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:10.999492] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1026 [16:55:11.530168] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1027 [16:55:11.778557] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217092162 rtp:3217169337 remote ntp:83ad387c39db5957, local time 1554294082 (now:78963403) [16:55:12.044400] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:12.574748] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1031 [16:55:12.798448] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217137145 rtp:3217214320 remote ntp:83ad387d3efbd326, local time 1554295102 (now:78964423) [16:55:12.798483] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78964423 (port: 60976) [16:55:12.961527] http_parse:1147 sock: 8, received CSeq: 50 [16:55:12.961648] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:12.961722] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:12.961793] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:13.105178] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:13.619091] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:13.818644] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217182129 rtp:3217259304 remote ntp:83ad387e441e5242, local time 1554296122 (now:78965443) [16:55:14.008674] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:1003] [W:54223 R:53221] [16:55:14.149375] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1026 [16:55:14.663796] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1027 [16:55:14.838995] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217227116 rtp:3217304291 remote ntp:83ad387f4944199f, local time 1554297142 (now:78966464) [16:55:14.931147] http_parse:1147 sock: 8, received CSeq: 51 [16:55:14.931237] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:14.931325] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:14.931396] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:15.194042] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:15.708509] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:15.858736] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217272092 rtp:3217349267 remote ntp:83ad38804e59d1df, local time 1554298162 (now:78967483) [16:55:16.239432] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:16.769458] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:16.878773] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217317075 rtp:3217394250 remote ntp:83ad3881537a4c61, local time 1554299182 (now:78968504) [16:55:16.878801] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78968504 (port: 60976) [16:55:16.940827] http_parse:1147 sock: 8, received CSeq: 52 [16:55:16.940914] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:16.941000] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:16.941075] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:17.284062] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:17.814910] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1030 [16:55:17.898342] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217362056 rtp:3217439231 remote ntp:83ad38825897396d, local time 1554300202 (now:78969523) [16:55:18.048875] buffer_put_packet:648 [0x7f90c80061d0]: fill [level:989] [W:54735 R:53747] [16:55:18.329759] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:18.859927] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:18.918774] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217407042 rtp:3217484217 remote ntp:83ad38835dbc3a42, local time 1554301222 (now:78970544) [16:55:18.961930] http_parse:1147 sock: 8, received CSeq: 53 [16:55:18.962159] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:18.962258] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:18.962347] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2 [16:55:19.390067] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:19.904589] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:19.938712] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217452020 rtp:3217529195 remote ntp:83ad388462d42e55, local time 1554302242 (now:78971563) [16:55:20.435092] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1028 [16:55:20.949476] _buffer_get_frame:946 [0x7f90c80061d0]: Buffer overrun 1025 [16:55:20.958470] rtp_thread_func:770 [0x7f90c80061d0]: sync packet rtp_latency:3217497003 rtp:3217574178 remote ntp:83ad388567f4eb40, local time 1554303262 (now:78972583) [16:55:20.958495] rtp_request_timing:865 [0x7f90c80061d0]: timing request now:78972583 (port: 60976) [16:55:20.965716] http_parse:1147 sock: 8, received CSeq: 54 [16:55:20.965899] http_parse:1147 sock: 8, received DACP-ID: EC8909CE188294D2 [16:55:20.966008] http_parse:1147 sock: 8, received Active-Remote: 409095689 [16:55:20.966143] http_parse:1147 sock: 8, received User-Agent: AirPlay/387.2`

fservida commented 2 years ago

I admit I don't really know how it works in the details, having just read the doc; however you mention being 2000km distant from the server, is that in the sense that you're trying to control it with a remote server? If that's the case you might need an even larger value for the latency (and maybe play with both). Unfortunately I cannot really understand these logs and will have to leave that to the devs...

Aviatorpaal commented 2 years ago

Thank you for your help in attempting to resolve this issue. The streaming was executed locally, the debugging is being done remotely, by me.

github-actions[bot] commented 2 years ago

There hasn't been any activity on this issue recently, so we clean up some of the older and inactive issues. Please make sure to update to the latest version and check if that solves the issue. Let us know if that works for you by leaving a comment 👍 This issue has now been marked as stale and will be closed if no further activity occurs. Thanks!