Open Bug 1713397 Opened 4 years ago Updated 2 years ago

Intermittent dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is out of sync!

Categories

(Core :: Audio/Video: Playback, defect, P5)

Product:
Core
Component:
Audio/Video: Playback
Type:
defect

Tracking

()

Status:
REOPENED
Milestone:
91 Branch
Tracking Flags:
Tracking Status
firefox-esr78 --- unaffected
firefox89 --- unaffected
firefox90 --- disabled
firefox91 --- disabled

People

(Reporter: intermittent-bug-filer, Assigned: alwu)

References

(Blocks 1 open bug, Regression)

Details

(4 keywords, Whiteboard: [retriggered][stockwell unknown])

Attachments

(1 file)

Bug 1713397 - disable a/v sync test on Linux tsan because of high intermittent failure rate.
(deleted), text/x-phabricator-request
Details

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=341089370&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YmGdZoS-T8KbWXTT4AA7CQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YmGdZoS-T8KbWXTT4AA7CQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2021-05-28T19:36:57.478Z] 19:36:57     INFO - TEST-START | dom/media/test/test_capture_stream_av_sync.html
[task 2021-05-28T19:36:57.492Z] 19:36:57     INFO - GECKO(2823) | [Child 2826, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-05-28T19:36:57.512Z] 19:36:57     INFO - GECKO(2823) | [Child 2826, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-05-28T19:36:57.512Z] 19:36:57     INFO - GECKO(2823) | [Child 2826, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-05-28T19:36:57.560Z] 19:36:57     INFO - GECKO(2823) | [Child 2826, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-05-28T19:36:57.777Z] 19:36:57     INFO - TEST-INFO | started process screencapture
[task 2021-05-28T19:36:57.855Z] 19:36:57     INFO - TEST-INFO | screencapture: exit 0
[task 2021-05-28T19:36:57.856Z] 19:36:57     INFO - Buffered messages logged at 19:36:57
[task 2021-05-28T19:36:57.856Z] 19:36:57     INFO - add_task | Entering test testAVSyncForStreamCapturing
[task 2021-05-28T19:36:57.856Z] 19:36:57     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | video started playing 
[task 2021-05-28T19:36:57.857Z] 19:36:57     INFO - paint audible frame
[task 2021-05-28T19:36:57.857Z] 19:36:57     INFO - find the square in [350, 216.546875], diff=5.0625
[task 2021-05-28T19:36:57.857Z] 19:36:57     INFO - data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAoAAAAHgCAYAAAA10dzkAAAO00lEQVR4nO3dwW4cVQKG0VvuNF4Q4AUgvFeSZ0JWXjECYYvlbCaeqjsLt82QiJlkAu6Uv3Ok36qWLZe9KX0qqbrHBAhalmVu2zbHGH//yda7vR5jXn3743w7xnw7xrweh13s53Exr8dX8803P8zXY/z+DwG7c3/de4QrH8CXRwAKQCgSgECaAPycALw9DdgbAQikCUABCEUCEEgTgB+/X8azhwB8JQBh1wQgkCYAPy0Ab8alAIQnQAACaQLw4/frOM6bcTmvnn8/XwpA2DUBCKQJQAEIRQIQSBOAnxKAh3kzjvPq+YtTAHobGNgrAQikCUABCEUCEEgTgAIQigQgkCYABSAUCUAgTQAKQCgSgECaABSAUCQAgTQBKAChSAACaQJQAEKRAATSBKAAhCIBCKQJQAEIRQIQSBOAAhCKBCCQJgAFIBQJQCBNAApAKBKAQJoAFIBQJACBNAEoAKFIAAJpAlAAQpEABNIEoACEIgEIpAlAAQhFAhBIE4ACEIoEIJAmAAUgFAlAIE0ACkAoEoBAmgAUgFAkAIE0ASgAoUgAAmkCUABCkQAE0gSgAIQiAQikCUABCEUCEEgTgAIQigQgkCYABSAUCUAgTQAKQCgSgECaABSAUCQAgTQBKAChSAACaQJQAEKRAATSlmWZc84PAnDbtr/+ZDsPwOtxmL+N43zz9Yv5aow5t/W0eTdgNwQgkCYABSAUCUAgTQAKQCgSgECaABSAUCQAgTQBKAChSAACae8H4H34CUABCE+ZAATSzhGAL8eYP30nAIHzEYBAmgAUgFAkAAEAYgQgkLOu68MdvmVZ5vF4fLgTCFAgAIGkdb37CLPLy8s554dPAQM8ZQIQSHvUzwIG+EIIQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCBNAAJFAhBIE4BAkQAE0gQgUCQAgTQBCBQJQCDtswJwO+1jf+79AZyJAATSzhKA63Y3gDMRgEDOv057N+ccz5Z5O/+/ALz/Petpf+bdaf847Z+nbds2t217CML713sZsF8CEMg5dwCO47M5nh3msixzWZZ5GHe7f72XAfslAIG0R30I5FSKr8eYV9/+ON+OMd+OMa/HYRf7eVzM6/HVfPPND/P1GPN/py/wpRKAQJoAFIBQJACBNAH4OQF4exqwNwIQSBOAAhCKBCCQJgA/fr+MZw8B+EoAwq4JQCBNAH5aAN6MSwEIT4AABNIE4Mfv13GcN+NyXj3/fr4UgLBrAhBIE4ACEIoEIJAmAD8lAA/zZhzn1fMXpwD0NjCwVwIQSBOAAhCKBCCQJgAFIBQJQCBNAApAKBKAQJoAFIBQJACBNAEoAKFIAAJpAlAAQpEABNIEoACEIgEIpAlAAQhFAhBIE4ACEIoEIJAmAAUgFAlAIE0ACkAoEoBAmgAUgFAkAIE0ASgAoUgAAmkCUABCkQAE0gSgAIQiAQikCUABCEUCEEgTgAIQigQgkCYABSAUCUAgTQAKQCgSgECaABSAUCQAgTQBKAChSAACaQJQAEKRAATSBKAAhCIBCKQJQAEIRQIQSBOAAhCKBCCQJgAFIBQJQCBNAApAKBKAQJoAFIBQJACBNAEoAKFIAAJpAlAAQpEABNIEoACEIgEIpC3LMuecHwTgtm1//cl2HoDX4zB/G8f55usX89UYc27rafNuwG4IQCBNAApAKBKAQJoAFIBQJACBNAEoAKFIAAJpAlAAQpEABNLeD8D78BOAAhCeMgEIpJ0jAF+OMX/6TgAC5yMAgTQBKAChSAACaY8agKdQOozlYRdj3F2EdzhgvwQgkHaOAJzrdjeAMxGAQNqjBiDAF0IAAmmPFYDbts11XR+On8KA/RKAQJo7gECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEhblmVu2/YQguu6nvkvAvj7CUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIE0AQgUCUAgTQACRQIQSBOAQJEABNIEIFAkAIGcbdsejscYAhDIEYBAjgAE6gQgkCMAgToBCOQIQKBOAAI5AhCoE4BAjgAE6gQgkCMAgToBCOQIQKBOAAI5AhCoE4BAjgAE6gQgkCMAgToBCOQIQKBOAAI5AhCoE4BAjgAE6gQgkCMAgToBCOQIQKBOAAI5AhCoE4BAjgAE6gQgkCMAgToBCOQIQKBOAAI5AhCoE4BAjgAE6gQgkPPfAvA/vwfwVAlAIEcAAnUCEEi7uLiYc/4eggIQKBCAQNJ96C3LMm9vbx/uAAIUCEAg5927dw/Hy7LM4/EoAIEUAQikbds213X19C+QIgABAGIEIABAjAAEAIgRgAAAMQIQACBGAAIAxAhAAIAYAQgAECMAAQBiBCAAQIwABACIEYAAADECEAAgRgACAMQIQACAGAEIABAjAAEAYgQgAECMAAQAiBGAAAAxAhAAIEYAAgDECEAAgBgBCAAQIwABAGIEIABAjAAEAIgRgAAAMQIQACBGAAIAxAhAAIAYAQgAECMAAQBiBCAAQIwABACIEYAAADECEAAgRgACAMQIQACAGAEIABAjAAEAYgQgAECMAAQAiBGAAAAxAhAAIEYAAgDECEAAgBgBCAAQIwABAGIEIABAjAAEAIgRgAAAMQIQACBGAAIAxAhAAIAYAQgAECMAAQBiBCAAQIwABACIEYAAADECEAAgRgACAMQIQACAGAEIABAjAAEAQtZ1nXNOAQgAUHB7e/vHANy2bZqZmZnZ0969McYcpy9mZmZm9oR3OBzmsixzjDH/DXZfGAhHzGTeAAAAAElFTkSuQmCC
[task 2021-05-28T19:36:57.858Z] 19:36:57     INFO - Buffered messages finished
[task 2021-05-28T19:36:57.858Z] 19:36:57     INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is out of sync! 
[task 2021-05-28T19:36:57.858Z] 19:36:57     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-05-28T19:36:57.858Z] 19:36:57     INFO -     captureStreamFromVideo/</analyser.notifyAnalysis@dom/media/test/test_capture_stream_av_sync.html:131:11
[task 2021-05-28T19:36:58.686Z] 19:36:58     INFO - paint audible frame
[task 2021-05-28T19:36:58.686Z] 19:36:58     INFO - find the square in [298, 216.465625], diff=0.1875
[task 2021-05-28T19:36:58.686Z] 19:36:58     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 1 times, a/v is in sync! 
[task 2021-05-28T19:36:59.670Z] 19:36:59     INFO - paint audible frame
[task 2021-05-28T19:36:59.670Z] 19:36:59     INFO - find the square in [298, 216.465625], diff=0.1875
[task 2021-05-28T19:36:59.670Z] 19:36:59     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 2 times, a/v is in sync! 
[task 2021-05-28T19:37:00.678Z] 19:37:00     INFO - paint audible frame
[task 2021-05-28T19:37:00.678Z] 19:37:00     INFO - find the square in [298, 216.465625], diff=0.1875
[task 2021-05-28T19:37:00.683Z] 19:37:00     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 3 times, a/v is in sync! 
[task 2021-05-28T19:37:01.694Z] 19:37:01     INFO - paint audible frame
[task 2021-05-28T19:37:01.695Z] 19:37:01     INFO - find the square in [310, 216.484375], diff=1.3125
[task 2021-05-28T19:37:01.696Z] 19:37:01     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 4 times, a/v is in sync! 
[task 2021-05-28T19:37:01.705Z] 19:37:01     INFO - add_task | Leaving test testAVSyncForStreamCapturing
[task 2021-05-28T19:37:01.705Z] 19:37:01     INFO - add_task | Entering test testAVSyncWhenSwitchingMediaSink
[task 2021-05-28T19:37:01.795Z] 19:37:01     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | video started playing 
[task 2021-05-28T19:37:01.832Z] 19:37:01     INFO - GECKO(2823) | [Child 2826, MediaDecoderStateMachine #1] WARNING: 119973550 Could not set cubeb stream name.: file /builds/worker/checkouts/gecko/dom/media/AudioStream.cpp:367
[task 2021-05-28T19:37:02.654Z] 19:37:02     INFO - GECKO(2823) | [Child 2826, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/checkouts/gecko/dom/base/Document.cpp:7515
[task 2021-05-28T19:37:02.654Z] 19:37:02     INFO - GECKO(2823) | [Child 2826, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/checkouts/gecko/dom/base/Document.cpp:7515
[task 2021-05-28T19:37:03.202Z] 19:37:03     INFO - skip the first audible frame
[task 2021-05-28T19:37:04.066Z] 19:37:04     INFO - paint audible frame
[task 2021-05-28T19:37:04.066Z] 19:37:04     INFO - find the square in [298, 216.465625], diff=0.1875
[task 2021-05-28T19:37:04.066Z] 19:37:04     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is in sync! 
[task 2021-05-28T19:37:05.052Z] 19:37:05     INFO - paint audible frame
[task 2021-05-28T19:37:05.052Z] 19:37:05     INFO - find the square in [308, 216.48125], diff=1.125
[task 2021-05-28T19:37:05.052Z] 19:37:05     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 1 times, a/v is in sync! 
[task 2021-05-28T19:37:06.047Z] 19:37:06     INFO - paint audible frame
[task 2021-05-28T19:37:06.047Z] 19:37:06     INFO - find the square in [310, 216.484375], diff=1.3125
[task 2021-05-28T19:37:06.047Z] 19:37:06     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 2 times, a/v is in sync! 
[task 2021-05-28T19:37:07.051Z] 19:37:07     INFO - paint audible frame
[task 2021-05-28T19:37:07.051Z] 19:37:07     INFO - find the square in [310, 216.484375], diff=1.3125
[task 2021-05-28T19:37:07.051Z] 19:37:07     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 3 times, a/v is in sync! 
[task 2021-05-28T19:37:08.022Z] 19:37:08     INFO - paint audible frame
[task 2021-05-28T19:37:08.035Z] 19:37:08     INFO - find the square in [300, 216.46875], diff=0.375
[task 2021-05-28T19:37:08.035Z] 19:37:08     INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 4 times, a/v is in sync! 
[task 2021-05-28T19:37:08.064Z] 19:37:08     INFO - add_task | Leaving test testAVSyncWhenSwitchingMediaSink
[task 2021-05-28T19:37:08.064Z] 19:37:08     INFO - GECKO(2823) | MEMORY STAT | vsize 6665MB | residentFast 124MB | heapAllocated 15MB
[task 2021-05-28T19:37:08.085Z] 19:37:08     INFO - TEST-OK | dom/media/test/test_capture_stream_av_sync.html | took 10612ms
[task 2021-05-28T19:37:08.149Z] 19:37:08     INFO - GECKO(2823) | [Child 2826, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-05-28T19:37:08.149Z] 19:37:08     INFO - TEST-START | Shutdown

Started appearing on tier 2 jobs:
https://treeherder.mozilla.org/logviewer?job_id=341104422&repo=autoland&lineNumber=2971

Regressed by: 1712598
Summary: Intermittent TV dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is out of sync! → Intermittent [tier 2] dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is out of sync!
Has Regression Range: --- → yes

Will fix that in D116285 as well.

Assignee: nobody → alwu
Summary: Intermittent [tier 2] dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is out of sync! → Intermittent dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is out of sync!

Set release status flags based on info from the regressing bug 1712598

status-firefox89: --- → unaffected
status-firefox90: --- → affected
status-firefox91: --- → affected
status-firefox-esr78: --- → unaffected
Whiteboard: [retriggered]

There are 31 total failures in the last 7 days on linux1804-64-tsan opt
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=344045676&repo=mozilla-central&lineNumber=2715

[task 2021-06-28T22:24:41.639Z] 22:24:41 INFO - TEST-START | dom/media/test/test_capture_stream_av_sync.html
[task 2021-06-28T22:24:49.484Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.496Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.503Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.513Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.532Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.534Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.543Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.550Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:49.563Z] 22:24:49 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:56.355Z] 22:24:56 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:56.368Z] 22:24:56 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:56.375Z] 22:24:56 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:24:56.386Z] 22:24:56 INFO - GECKO(1582) | [Child 1667, MediaDecoderStateMachine #1] WARNING: Decoder=7b5800151e00 Decode error: NS_ERROR_DOM_MEDIA_DEMUXER_ERR (0x806e000c): file /builds/worker/checkouts/gecko/dom/media/MediaDecoderStateMachine.cpp:3543
[task 2021-06-28T22:25:16.181Z] 22:25:16 INFO - TEST-INFO | started process screentopng
[task 2021-06-28T22:25:16.592Z] 22:25:16 INFO - TEST-INFO | screentopng: exit 0
[task 2021-06-28T22:25:16.592Z] 22:25:16 INFO - Buffered messages logged at 22:24:41
[task 2021-06-28T22:25:16.592Z] 22:25:16 INFO - add_task | Entering test testAVSyncForStreamCapturing
[task 2021-06-28T22:25:16.592Z] 22:25:16 INFO - Buffered messages logged at 22:24:43
[task 2021-06-28T22:25:16.592Z] 22:25:16 INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | video started playing

[task 2021-06-28T22:25:16.653Z] 22:25:16 INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 0 times, a/v is in sync!
[task 2021-06-28T22:25:16.653Z] 22:25:16 INFO - paint audible frame
[task 2021-06-28T22:25:16.653Z] 22:25:16 INFO - find the square in diff=14.625
[task 2021-06-28T22:25:16.653Z] 22:25:16 INFO - timeOffset=0.014511999999999858, frameOffset=0.9069999999999911
[task 2021-06-28T22:25:16.656Z] 22:25:16 INFO - adjusted diff to 13.718000000000009 (time=12.014512)
[task 2021-06-28T22:25:16.656Z] 22:25:16 INFO - Buffered messages logged at 22:25:16
[task 2021-06-28T22:25:16.656Z] 22:25:16 INFO - data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAoAAAAHgCAYAAAA10dzkAAAO6klEQVR4nO3cQWpj1xpG0XNtRE2ibEIaIfMKGZWnGV4jejr/a1hlqgJxHJJXrtJeCz4s5IbPVcNsLOuuAQhaa33xlZB93Zxn5jy/rjVPHx9m1t3McT9zHDPHMfu4n33cX5/7dvf08ef5Za2X64HX7L1nrTV+8wFJAjBMABImAIE0ARh2awH48KMA5M0EIJAmAMMEIGECEEgTgGE3E4CnmeM0Tw+PzwG4z8+DVwhAIE0AhglAwgQgkCYAwwQgYQIQSBOAYQKQMAEIpAnAsFsLwMePXwTgZWYu7/oC8y0TgECaAAwTgIQJQCBNAIbdSgCuDzPrw2cBeJnZFwHIqwQgkCYAwwQgYQIQSBOAYQKQMAEIpAnAMAFImAAE0gRgmAAkTAACaQIwTAASJgCBNAEYJgAJE4BAmgAME4CECUAgTQCGCUDCBCCQJgDDBCBhAhBIE4BhApAwAQikCcAwAUiYAATSBGCYACRMAAJpAjBMABImAIE0ARgmAAkTgECaAAwTgIQJQCBNAIYJQMIEIJAmAMMEIGECEEgTgGECkDABCKQJwDABSJgABNIEYJgAJEwAAmkCMEwAEiYAgTQBGCYACROAQJoADBOAhAlAIE0AIgApEoBAmgBEAFIkAIE0AchcU0kAUiIAgTQBiACkSAACaQKQ7z4Aj9PMcRKA/C0CEEgTgAhAigQgkCYAw15uA3MjAfjweA3A5+sSgLxGAAJpAjBMABImAIE0ARgmAAkTgECaAAwTgIQJQCBNAIbdTAA+7+nhBwHImwlAIE0AhglAwgQgkCYAw/5xAJ6ue//4ew7AnwQgbyYAgTQBGCYACROAAFd779l7v/cxAP7vBCCQttaa0+k0d3d3730UgK9GAAJp9/f3M/McgpeLN8yABgEIpH0Kv+M45nw+ewsYSBCAQNpaa/becxzHXC4XAQgkCEAg7fMA9CEQoEIAAmmfbv9yHMfMjAAEEgQgkOY+gECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBQJACBNAEIFAlAIE0AAkUCEEgTgECRAATSBCBvtff+xvf77P37e79MfCcEIJAmALkd/70O/poABNIEILfjP9ddroM/JwCBNAHIJ2utm5gA5C0EIJAmAMP2dXOemfP8utY8fXyYWXczx/3Mccwcx+zjfvZxf33u293Tx5/nl7VergdeIwCBNAEYJgAJE4BAmgAMu7UAfPhRAPJmAhBIE4BhApAwAQikCcCwmwnA08xxmqeHx+cA3OfnwSsEIJAmAMMEIGECEEgTgGECkDABCKQJwDABSJgABNIEYNitBeDjxy8C0O2geY0ABNIEYJgAJEwAAmkCMOxWAnB9mFkfPgvAy8y+CEBeJQCBNAEYJgAJE4BAmgAME4CECUAgTQCGCUDCBCCQJgDDBCBhAhBIE4BhApAwAQikCcAwAUiYAATSBGCYACRMAAJpAjBMABImAIE0ARgmAAkTgECaAAwTgIQJQCBNAIYJQMIEIJAmAMMEIGECEEgTgGECkDABCKQJwDABSJgABNIEYJgAJEwAAmkCMEwAEiYAgTQBGCYACROAQJoADBOAhAlAIE0AhglAwgQgkCYAwwQgYQIQSBOAYQKQMAEIpAnAMAFImAAE0gRgmAAkTAACaQIQAUiRAATSBCACkCIBCKQJQOaaSgKQEgEIpAlABCBFAhBIE4B89wF4nGaOkwDkbxGAQJoARABSJACBNAEY9nIbmBsJwIfHawA+X5cA5DUCEEgTgGECkDABCKQJwDABSJgABNIEYJgAJEwAAmkCMOxmAvB5Tw8/CEDeTAACaQIwTAASJgCBNAEY9o8D8HTd+8ffcwD+JAB5MwEIpAnAMAFImAAE0gQgn1JprXUTg7cQgECaAOStfyvbe7+6f8tf/Zw/3fw2e377187BbROAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJAmAIEiAQikCUCgSAACaQIQKBKAQJoABIoEIJCz9355/McA/Px7ALdKAAI5nyJv7z3HcczMzN3d3czMXC6XdzsXwNciAIGsvfdL+K21xB+QIQCBtLXW3N3d+R9AIEUAAml779l7z/l8fu+jAHw1AhAAIEYAAgDECEAAgBgBCAAQIwABAGIEIABAjAAEAIgRgAAAMQIQACBGAAIAxAhAAIAYAQgAECMAAQBiBCAAQIwABACIEYAAADECEAAgRgACAMQIQACAGAEIABAjAAEAYgQgAECMAAQAiBGAAAAxAhAAIEYAAgDECEAAgBgBCAAQIwABAGIEIABAjAAEAIgRgAAAMQIQACBGAAIAxAhAAIAYAQgAECMAAQBiBCAAQIwABACIEYAAADECEAAgRgACAMQIQACAGAEIABAjAAEAYgQgAECMAAQAiBGAAAAxAhAAIEYAAgDECEAAgBgBCAAQIwABAGIEIABAjAAEAIgRgAAAMQIQACBGAAIAxAhAAIAYAQgAECMAAQBiBCAAQIwABACIEYAAADECEAAgZO89MzPHcQhAAICStdasvfeYmZmZ2e3ucrm8PD6OY9Zaa8zMzMyss/8BO6zCEIUXDN4AAAAASUVORK5CYII=
[task 2021-06-28T22:25:16.656Z] 22:25:16 INFO - Buffered messages finished
[task 2021-06-28T22:25:16.656Z] 22:25:16 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_capture_stream_av_sync.html | test 1 times, a/v is out of sync!
[task 2021-06-28T22:25:16.657Z] 22:25:16 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-06-28T22:25:16.657Z] 22:25:16 INFO - testAVSync/</analyser.notifyAnalysis@dom/media/test/test_capture_stream_av_sync.html:161:11
[task 2021-06-28T22:25:16.657Z] 22:25:16 INFO - paint audible frame
[task 2021-06-28T22:25:16.657Z] 22:25:16 INFO - find the square in diff=2.625
[task 2021-06-28T22:25:16.657Z] 22:25:16 INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 2 times, a/v is in sync!
[task 2021-06-28T22:25:17.018Z] 22:25:17 INFO - paint audible frame
[task 2021-06-28T22:25:17.055Z] 22:25:17 INFO - find the square in diff=3.9375
[task 2021-06-28T22:25:17.058Z] 22:25:17 INFO - TEST-PASS | dom/media/test/test_capture_stream_av_sync.html | test 3 times, a/v is in sync!
[task 2021-06-28T22:25:17.088Z] 22:25:17 INFO - add_task | Leaving test testAVSyncWhenChangingPlaybackRate
[task 2021-06-28T22:25:17.125Z] 22:25:17 INFO - GECKO(1582) | MEMORY STAT | vsize 130551012MB | residentFast 917MB
[task 2021-06-28T22:25:17.273Z] 22:25:17 INFO - TEST-OK | dom/media/test/test_capture_stream_av_sync.html | took 35633ms

Whiteboard: [retriggered] → [retriggered][stockwell needswork:owner]

This test is unstable on Linux tsan and I currently don't have a good way to tune it. So I will simply turn it off on Linux tsan.

Pushed by alwu@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/0127079873ee disable a/v sync test on Linux tsan because of high intermittent failure rate. r=bryce

https://hg.mozilla.org/mozilla-central/rev/0127079873ee

Status: NEW → RESOLVED
Closed: 3 years ago
status-firefox91: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 91 Branch

We don't test on tsan on branches afaict, updating status for 90.

status-firefox90: affected → disabled
Status: RESOLVED → REOPENED
Keywords: test-disabled
Resolution: FIXED → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE

autonag wants leave-open

Keywords: leave-open
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Let's follow progress in Bug 1719881. This being still suggested by Treeherder might split the failure rate between these two which look like the same bug.

Keywords: intermittent-failure
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: