GNOME Bugzilla – Bug 796438
audiolatency gives strange results with RTP streams
Last modified: 2018-11-03 14:24:21 UTC
New audiolatency element gives strange results for RTP streams. I use following pipeline for tests: gst-launch-1.0 audiolatency name=al print-latency=true ! audioconvert ! audioresample ! opusenc ! rtpopuspay min-ptime=20000000 max-ptime=20000000 ! rtpopusdepay ! opusdec ! audioconvert ! audioresample ! al. * Gstreamer 1.14.0 was giving times which probably were negative: New clock: GstSystemClock Redistribute latency... last latency: 17ms, running average: 17ms last latency: 7ms, running average: 12ms last latency: 9ms, running average: 11ms last latency: 1ms, running average: 8ms last latency: 1ms, running average: 7ms last latency: 1ms, running average: 4ms last latency: 0ms, running average: 2ms last latency: 0ms, running average: 0ms last latency: 0ms, running average: 0ms last latency: 950ms, running average: 190ms last latency: 950ms, running average: 380ms last latency: 950ms, running average: 570ms last latency: 952ms, running average: 760ms last latency: 949ms, running average: 950ms last latency: 0ms, running average: 760ms last latency: 950ms, running average: 760ms last latency: 951ms, running average: 760ms last latency: 954ms, running average: 761ms last latency: 950ms, running average: 761ms last latency: 959ms, running average: 953ms last latency: 950ms, running average: 953ms last latency: 950ms, running average: 953ms last latency: 0ms, running average: 762ms last latency: 950ms, running average: 762ms last latency: 950ms, running average: 760ms last latency: 952ms, running average: 760ms last latency: 950ms, running average: 760ms last latency: 950ms, running average: 950ms last latency: 950ms, running average: 950ms ^Chandling interrupt. Interrupt: Stopping pipeline ... Execution ended after 0:00:27.180722643 Setting pipeline to PAUSED ... * Gstreamer 1.14.1 is better, however reported time keeps increasing: New clock: GstSystemClock last latency: 10ms, running average: 10ms Redistribute latency... last latency: 2ms, running average: 6ms last latency: 23ms, running average: 12ms last latency: 3ms, running average: 10ms last latency: 2ms, running average: 8ms last latency: 3ms, running average: 7ms last latency: 2ms, running average: 7ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 20ms, running average: 6ms last latency: 25ms, running average: 10ms last latency: 21ms, running average: 14ms last latency: 23ms, running average: 18ms last latency: 21ms, running average: 22ms last latency: 20ms, running average: 22ms last latency: 22ms, running average: 21ms last latency: 31ms, running average: 24ms last latency: 22ms, running average: 23ms last latency: 38ms, running average: 27ms last latency: 25ms, running average: 28ms last latency: 22ms, running average: 28ms last latency: 48ms, running average: 31ms last latency: 68ms, running average: 40ms last latency: 79ms, running average: 48ms last latency: 76ms, running average: 59ms last latency: 77ms, running average: 70ms last latency: 69ms, running average: 74ms last latency: 71ms, running average: 75ms last latency: 65ms, running average: 72ms last latency: 53ms, running average: 67ms last latency: 46ms, running average: 61ms last latency: 25ms, running average: 52ms last latency: 24ms, running average: 43ms last latency: 20ms, running average: 34ms last latency: 23ms, running average: 28ms last latency: 35ms, running average: 25ms last latency: 28ms, running average: 26ms last latency: 22ms, running average: 25ms last latency: 20ms, running average: 25ms last latency: 38ms, running average: 28ms last latency: 42ms, running average: 30ms last latency: 57ms, running average: 36ms last latency: 69ms, running average: 45ms last latency: 75ms, running average: 56ms last latency: 82ms, running average: 65ms last latency: 94ms, running average: 76ms last latency: 83ms, running average: 81ms last latency: 102ms, running average: 87ms last latency: 108ms, running average: 94ms last latency: 94ms, running average: 96ms last latency: 94ms, running average: 96ms last latency: 93ms, running average: 98ms last latency: 83ms, running average: 94ms last latency: 95ms, running average: 92ms last latency: 89ms, running average: 91ms last latency: 79ms, running average: 88ms last latency: 82ms, running average: 86ms last latency: 82ms, running average: 85ms last latency: 78ms, running average: 82ms last latency: 76ms, running average: 79ms last latency: 95ms, running average: 83ms last latency: 105ms, running average: 87ms last latency: 118ms, running average: 94ms last latency: 126ms, running average: 104ms last latency: 138ms, running average: 116ms last latency: 135ms, running average: 124ms last latency: 143ms, running average: 132ms last latency: 145ms, running average: 138ms last latency: 135ms, running average: 139ms last latency: 153ms, running average: 142ms last latency: 167ms, running average: 149ms last latency: 175ms, running average: 155ms last latency: 189ms, running average: 164ms last latency: 185ms, running average: 174ms last latency: 198ms, running average: 183ms last latency: 207ms, running average: 191ms last latency: 205ms, running average: 197ms last latency: 194ms, running average: 198ms last latency: 194ms, running average: 199ms last latency: 209ms, running average: 202ms last latency: 206ms, running average: 202ms last latency: 207ms, running average: 202ms last latency: 203ms, running average: 204ms last latency: 200ms, running average: 205ms last latency: 213ms, running average: 206ms last latency: 224ms, running average: 209ms last latency: 240ms, running average: 216ms last latency: 222ms, running average: 220ms ^Chandling interrupt. Interrupt: Stopping pipeline ... Execution ended after 0:01:37.927549255 Setting pipeline to PAUSED ...
I have ran one more test some time later and found that 1.14.1 also can give negative numbers: New clock: GstSystemClock last latency: 10ms, running average: 10ms Redistribute latency... last latency: 2ms, running average: 6ms last latency: 3ms, running average: 5ms last latency: 2ms, running average: 4ms last latency: 954ms, running average: 194ms last latency: 907ms, running average: 374ms last latency: 894ms, running average: 552ms last latency: 918ms, running average: 735ms last latency: 901ms, running average: 915ms last latency: 895ms, running average: 903ms last latency: 932ms, running average: 908ms last latency: 929ms, running average: 915ms last latency: 914ms, running average: 914ms last latency: 930ms, running average: 920ms last latency: 921ms, running average: 925ms last latency: 3ms, running average: 739ms last latency: 2ms, running average: 554ms last latency: 2ms, running average: 372ms last latency: 2ms, running average: 186ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 3ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 2ms, running average: 2ms last latency: 15ms, running average: 5ms last latency: 46ms, running average: 13ms last latency: 86ms, running average: 30ms last latency: 104ms, running average: 51ms last latency: 84ms, running average: 67ms last latency: 91ms, running average: 82ms last latency: 47ms, running average: 83ms last latency: 60ms, running average: 77ms last latency: 48ms, running average: 66ms last latency: 52ms, running average: 60ms last latency: 51ms, running average: 52ms ^Chandling interrupt. Interrupt: Stopping pipeline ... Execution ended after 0:00:38.547020332 Setting pipeline to PAUSED ...
-- GitLab Migration Automatic Message -- This bug has been migrated to freedesktop.org's GitLab instance and has been closed from further activity. You can subscribe and participate further through the new bug through this link to our GitLab instance: https://gitlab.freedesktop.org/gstreamer/gst-plugins-bad/issues/719.