Open Bug 1110922 Opened 10 years ago Updated 8 days ago

Intermittent dom/media/test/test_played.html | single tracking bug

Categories

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

Product:
Core
Component:
Audio/Video: Playback
Platform:
x86_64
Windows 10
Type:
defect

Tracking

()

People

(Reporter: RyanVM, Unassigned)

References

(Depends on 1 open bug)

Details

(4 keywords, Whiteboard: [stockwell disabled])

Attachments

(2 files, 1 obsolete file)

1110922_disable_vbr_mp3.patch
1.28 KB, patch
padenot
: review+
Details | Diff | Splinter Review
Bug 1110922 - Disable dom/media/test/test_played.html on multiple platforms to reduce the high failure count. r?#intermittent-reviewers
48 bytes, text/x-phabricator-request
Details | Review
08:16:50 INFO - 2385 INFO TEST-START | /tests/dom/media/test/test_played.html 08:22:14 INFO - TEST-INFO | screenshot: exit status 0 08:22:14 INFO - <snipped 182 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test> 08:22:14 INFO - 2386 INFO Watchdog remaining tests= test5-big.wav-28,test5-vbr.mp3-33 08:22:14 INFO - 2387 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test5-vbr.mp3-33: start of first range shall be 0 08:22:14 INFO - 2388 INFO [finished test5-vbr.mp3-33] remaining= test5-big.wav-28 08:22:14 INFO - 2389 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test5-vbr.mp3-33] Length of array should match number of running tests 08:22:14 INFO - 2390 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test5-bug495794.ogg-34] Length of array should match number of running tests 08:22:14 INFO - 2391 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test5-bug495794.ogg-34: start of first range shall be 0 08:22:14 INFO - 2392 INFO [finished test5-bug495794.ogg-34] remaining= test5-big.wav-28 08:22:14 INFO - 2393 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test5-bug495794.ogg-34] Length of array should match number of running tests 08:22:14 INFO - 2394 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test6-big.wav-35] Length of array should match number of running tests 08:22:14 INFO - 2395 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-big.wav-35: There should be at least one range=2 08:22:14 INFO - 2396 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-big.wav-35: Start of first range should be the sixth of the duration 08:22:14 INFO - 2397 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-big.wav-35: End of last range should be greater that five times the sixth of the duration 08:22:14 INFO - 2398 INFO [finished test6-big.wav-35] remaining= test5-big.wav-28 08:22:14 INFO - 2399 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test6-big.wav-35] Length of array should match number of running tests 08:22:14 INFO - 2400 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test6-seek.ogv-36] Length of array should match number of running tests 08:22:14 INFO - 2401 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-seek.ogv-36: There should be at least one range=2 08:22:14 INFO - 2402 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-seek.ogv-36: Start of first range should be the sixth of the duration 08:22:14 INFO - 2403 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-seek.ogv-36: End of last range should be greater that five times the sixth of the duration 08:22:14 INFO - 2404 INFO [finished test6-seek.ogv-36] remaining= test5-big.wav-28 08:22:14 INFO - 2405 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test6-seek.ogv-36] Length of array should match number of running tests 08:22:14 INFO - 2406 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test6-seek.webm-37] Length of array should match number of running tests 08:22:14 INFO - 2407 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-seek.webm-37: There should be at least one range=2 08:22:14 INFO - 2408 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-seek.webm-37: Start of first range should be the sixth of the duration 08:22:14 INFO - 2409 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-seek.webm-37: End of last range should be greater that five times the sixth of the duration 08:22:14 INFO - 2410 INFO [finished test6-seek.webm-37] remaining= test5-big.wav-28 08:22:14 INFO - 2411 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test6-seek.webm-37] Length of array should match number of running tests 08:22:14 INFO - 2412 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test6-gizmo.mp4-38] Length of array should match number of running tests 08:22:14 INFO - 2413 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-gizmo.mp4-38: There should be at least one range=2 08:22:14 INFO - 2414 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-gizmo.mp4-38: Start of first range should be the sixth of the duration 08:22:14 INFO - 2415 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-gizmo.mp4-38: End of last range should be greater that five times the sixth of the duration 08:22:14 INFO - 2416 INFO [finished test6-gizmo.mp4-38] remaining= test5-big.wav-28 08:22:14 INFO - 2417 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test6-gizmo.mp4-38] Length of array should match number of running tests 08:22:14 INFO - 2418 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test6-owl.mp3-39] Length of array should match number of running tests 08:22:14 INFO - 2419 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-owl.mp3-39: There should be at least one range=2 08:22:14 INFO - 2420 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-owl.mp3-39: Start of first range should be the sixth of the duration 08:22:14 INFO - 2421 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-owl.mp3-39: End of last range should be greater that five times the sixth of the duration 08:22:14 INFO - 2422 INFO [finished test6-owl.mp3-39] remaining= test5-big.wav-28 08:22:14 INFO - 2423 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test6-owl.mp3-39] Length of array should match number of running tests 08:22:14 INFO - 2424 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test6-vbr.mp3-40] Length of array should match number of running tests 08:22:14 INFO - 2425 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-vbr.mp3-40: There should be at least one range=2 08:22:14 INFO - 2426 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-vbr.mp3-40: Start of first range should be the sixth of the duration 08:22:14 INFO - 2427 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-vbr.mp3-40: End of last range should be greater that five times the sixth of the duration 08:22:14 INFO - 2428 INFO [finished test6-vbr.mp3-40] remaining= test5-big.wav-28 08:22:14 INFO - 2429 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test6-vbr.mp3-40] Length of array should match number of running tests 08:22:14 INFO - 2430 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test6-bug495794.ogg-41] Length of array should match number of running tests 08:22:14 INFO - 2431 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-bug495794.ogg-41: There should be at least one range=1 08:22:14 INFO - 2432 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-bug495794.ogg-41: Start of first range should be the sixth of the duration 08:22:14 INFO - 2433 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test6-bug495794.ogg-41: End of last range should be greater that five times the sixth of the duration 08:22:14 INFO - 2434 INFO [finished test6-bug495794.ogg-41] remaining= test5-big.wav-28 08:22:14 INFO - 2435 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test6-bug495794.ogg-41] Length of array should match number of running tests 08:22:14 INFO - 2436 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test7-big.wav-42] Length of array should match number of running tests 08:22:14 INFO - 2437 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-big.wav-42: played.length should be 0 08:22:14 INFO - 2438 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-big.wav-42: played.length should be 0 08:22:14 INFO - 2439 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-big.wav-42: played.length should be 0 08:22:14 INFO - 2440 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-big.wav-42: played.length should be 0 08:22:14 INFO - 2441 INFO [finished test7-big.wav-42] remaining= test5-big.wav-28 08:22:14 INFO - 2442 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test7-big.wav-42] Length of array should match number of running tests 08:22:14 INFO - 2443 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test7-seek.ogv-43] Length of array should match number of running tests 08:22:14 INFO - 2444 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.ogv-43: played.length should be 0 08:22:14 INFO - 2445 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.ogv-43: played.length should be 0 08:22:14 INFO - 2446 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.ogv-43: played.length should be 0 08:22:14 INFO - 2447 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.ogv-43: played.length should be 0 08:22:14 INFO - 2448 INFO [finished test7-seek.ogv-43] remaining= test5-big.wav-28 08:22:14 INFO - 2449 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test7-seek.ogv-43] Length of array should match number of running tests 08:22:14 INFO - 2450 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test7-seek.webm-44] Length of array should match number of running tests 08:22:14 INFO - 2451 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.webm-44: played.length should be 0 08:22:14 INFO - 2452 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.webm-44: played.length should be 0 08:22:14 INFO - 2453 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.webm-44: played.length should be 0 08:22:14 INFO - 2454 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-seek.webm-44: played.length should be 0 08:22:14 INFO - 2455 INFO [finished test7-seek.webm-44] remaining= test5-big.wav-28 08:22:14 INFO - 2456 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test7-seek.webm-44] Length of array should match number of running tests 08:22:14 INFO - 2457 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test7-gizmo.mp4-45] Length of array should match number of running tests 08:22:14 INFO - 2458 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-gizmo.mp4-45: played.length should be 0 08:22:14 INFO - 2459 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-gizmo.mp4-45: played.length should be 0 08:22:14 INFO - 2460 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-gizmo.mp4-45: played.length should be 0 08:22:14 INFO - 2461 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-gizmo.mp4-45: played.length should be 0 08:22:14 INFO - 2462 INFO [finished test7-gizmo.mp4-45] remaining= test5-big.wav-28 08:22:14 INFO - 2463 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test7-gizmo.mp4-45] Length of array should match number of running tests 08:22:14 INFO - 2464 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test7-owl.mp3-46] Length of array should match number of running tests 08:22:14 INFO - 2465 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-owl.mp3-46: played.length should be 0 08:22:14 INFO - 2466 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-owl.mp3-46: played.length should be 0 08:22:14 INFO - 2467 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-owl.mp3-46: played.length should be 0 08:22:14 INFO - 2468 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-owl.mp3-46: played.length should be 0 08:22:14 INFO - 2469 INFO [finished test7-owl.mp3-46] remaining= test5-big.wav-28 08:22:14 INFO - 2470 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test7-owl.mp3-46] Length of array should match number of running tests 08:22:14 INFO - 2471 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test7-vbr.mp3-47] Length of array should match number of running tests 08:22:14 INFO - 2472 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-vbr.mp3-47: played.length should be 0 08:22:14 INFO - 2473 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-vbr.mp3-47: played.length should be 0 08:22:14 INFO - 2474 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-vbr.mp3-47: played.length should be 0 08:22:14 INFO - 2475 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-vbr.mp3-47: played.length should be 0 08:22:14 INFO - 2476 INFO [finished test7-vbr.mp3-47] remaining= test5-big.wav-28 08:22:14 INFO - 2477 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test7-vbr.mp3-47] Length of array should match number of running tests 08:22:14 INFO - 2478 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [started test7-bug495794.ogg-48] Length of array should match number of running tests 08:22:14 INFO - 2479 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-bug495794.ogg-48: played.length should be 0 08:22:14 INFO - 2480 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-bug495794.ogg-48: played.length should be 0 08:22:14 INFO - 2481 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-bug495794.ogg-48: played.length should be 0 08:22:14 INFO - 2482 INFO TEST-PASS | /tests/dom/media/test/test_played.html | test7-bug495794.ogg-48: played.length should be 0 08:22:14 INFO - 2483 INFO [finished test7-bug495794.ogg-48] remaining= test5-big.wav-28 08:22:14 INFO - 2484 INFO TEST-PASS | /tests/dom/media/test/test_played.html | [finished test7-bug495794.ogg-48] Length of array should match number of running tests 08:22:14 INFO - 2485 INFO Watchdog remaining tests= test5-big.wav-28 08:22:14 INFO - 2486 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/test/test_played.html | Test timed out. - expected PASS 08:22:15 INFO - 2487 INFO TEST-OK | /tests/dom/media/test/test_played.html | took 324293ms
Let's add some logs for debugging.
Assignee: nobody → jwwang
Status: NEW → ASSIGNED
Attachment #8540555 - Flags: review?(cpearce)
Comment on attachment 8540555 [details] [diff] [review] 1110922_add_logs_test_played.html.patch Review of attachment 8540555 [details] [diff] [review]: ----------------------------------------------------------------- Of course, adding logging will affect the timing, and probably change the orange profile...
Attachment #8540555 - Flags: review?(cpearce) → review+
Wish our best luck to get some clues from the logs.
Keywords: checkin-needed, leave-open
(In reply to TBPL Robot from comment #25) 10:31:11 INFO - Unable to initialize audio client: 88890017. 10:31:11 INFO - [2772] WARNING: AudioStream::OpenCubeb() f3c3d60f40 failed to init cubeb: file c:\builds\moz2_slave\m-in-w64-d-0000000000000000000\build\dom\media\AudioStream.cpp, line 513 10:31:11 INFO - [2772] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file c:\builds\moz2_slave\m-in-w64-d-0000000000000000000\build\dom\media\AudioStream.cpp, line 393 10:31:11 INFO - [2772] WARNING: Initializing AudioStream failed.: file c:\builds\moz2_slave\m-in-w64-d-0000000000000000000\build\dom\media\AudioSink.cpp, line 155 10:31:11 INFO - [2772] WARNING: Decoder=f3c50d2200 Decode error, changed state to SHUTDOWN due to error: file c:\builds\moz2_slave\m-in-w64-d-0000000000000000000\build\dom\media\MediaDecoderStateMachine.cpp, line 2064 The test case timed out because of the audio client error = 0x88890017 which is AUDCLNT_E_CPUUSAGE_EXCEEDED (http://msdn.microsoft.com/en-us/library/windows/desktop/dd370875%28v=vs.85%29.aspx). Hi Paul, Do you have any idea about this error code?
Flags: needinfo?(padenot)
tl; dr: This means that too much time has been spent in the audio callback. Longer explanation: audio thread have a very high priority, so it's easy to DoS the system of one spends too much time in an audio thread, WASAPI implements some counter measure to make sure it does not happen. The code in an audio callback need to meet some sort of real time guarantee: let's say the callback tells you you need to fill in a 1024 frames buffer, you have exactly 1024 / 44100. * 0.4 (for a sample rate of 44100) seconds to fill it and return. The 0.4 is mandated by the system, and is apparently configurable (we use the default). If the code cannot make it because it spends too much time computing the audio, then WASAPI will output silence, and note that there was what is called an under-run. After some under-runs, and because this has the potential to DoS a core, WASAPI will refuse to create new audio streams (and existing streams will stop calling their callbacks), until some time passes (and the CPU load is acceptable). This can mean a couple things: - The machine is super overloaded, and even high priority threads take a lot of time to complete, maybe we could try lowering the number of tests we run in parallel ? - There is some thing fishy in the audio callback itself, maybe we could profile locally to see what's up ? - We take too much time releasing CPU-intensive resources ? Are we leaking threads ? Borrowing a slave a running simple Task Manager sanity check during a test run could be worth it to get a better sense of the issue.
Flags: needinfo?(padenot)
Thanks for the explanation. It is interesting to observe that the error happened on big.wav while vbr.mp3 was playing through comment 21 to comment 25. It is also interesting that vbr.mp3 is not used in any tests other than test_played.html. I will try to remove vbr.mp3 from the test files to see how it goes.
In failures of comment 21 to comment 25, we observe that AUDCLNT_E_CPUUSAGE_EXCEEDED happens in big.wav after seeking while vbr.mp3 is playing. I suspect if the audio callback of vbr.mp3 takes longer than expected and causes audio client creation failure of big.wav. I will try to disable vbr.mp3 in this test to see how it goes.
Attachment #8540555 - Attachment is obsolete: true
Attachment #8547319 - Flags: review?(padenot)
A possible explanation would be that the sample rate of those files are not the same as the sample rate of the WASAPI audio device, so we need to resample. This would cause CPU usage in the callback. Although I would not expect the resampling to be so expensive that it triggers the issue we have here.
Comment on attachment 8547319 [details] [diff] [review] 1110922_disable_vbr_mp3.patch Review of attachment 8547319 [details] [diff] [review]: ----------------------------------------------------------------- This is at least worth a shot.
Attachment #8547319 - Flags: review?(padenot) → review+
Seems that the timeouts are almost happened at the file |test6-seek.webm-32|.
The duration of the file is 4 second, it looks like the onTimeUpdate disappeared. The testcase will finish if it receive onTimeUpdate to 2 second. LOG from my desktop: It shows that the onTimeUpdate is stopped after 1.6s onTimeUpdate_end 1.468773 4 109 INFO test6-seek.webm-0: got waiting 110 INFO test6-seek.webm-0: got canplay 111 INFO test6-seek.webm-0: got playing onTimeUpdate_end 1.501649 4 onTimeUpdate_end 1.639399 4 112 INFO test6-seek.webm-0: got waiting 123 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_played.html | Test timed out. - expected PASS
There are 20+ tasks (MediaDecoderReader::ThrottledNotifyDataArrived) accumulated in the |MediaDecoderReader::mTaskQueue| cause the timeout. Bug 1175768 move the memcpy from mainthread to other thread may be the root cause.
Blocks: 1175768
(In reply to Benjamin Chen [:bechen] from comment #58) > There are 20+ tasks (MediaDecoderReader::ThrottledNotifyDataArrived) > accumulated in the |MediaDecoderReader::mTaskQueue| cause the timeout. > > Bug 1175768 move the memcpy from mainthread to other thread may be the root > cause. There is a quick and simple fix. Swap the order of 466, 476 lines. https://dxr.mozilla.org/mozilla-central/source/dom/media/MediaResource.cpp?from=MEdiaResource.cpp#466 https://dxr.mozilla.org/mozilla-central/source/dom/media/MediaResource.cpp?from=MEdiaResource.cpp#476 Becaseu the WebMReader will call |SilentReadAt| back to the MediaResource, we should put the data into |mCacheStream| first then notify WebMReader.
Are you sure this would work? The tail dispatcher should ensure data is put into cache before WebMReader reads it.
(In reply to JW Wang [:jwwang] from comment #60) > Are you sure this would work? The tail dispatcher should ensure data is put > into cache before WebMReader reads it. You are right, I forgot the tail dispatcher.
Depends on: 1184429
Component: Audio/Video → Audio/Video: Playback
P5
Priority: -- → P5
(In reply to OrangeFactor Robot from comment #77) > 9 failures in 947 pushes (0.01 failures/push) were associated with this bug > in the last 7 days. > > Repository breakdown: > * autoland: 5 > * mozilla-inbound: 4 > > Platform breakdown: > * windows10-64: 6 > * windows10-64-stylo-disabled: 3 > > For more details, see: > https://brasstacks.mozilla.com/orangefactor/ > ?display=Bug&bugid=1110922&startday=2017-10-09&endday=2017-10-15&tree=all Windows 10 timeout.
Depends on: 1407553
OS: Windows 8.1 → Windows 10
There are 23 total failures in the last 7 days, all on windows, Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=200959009&repo=mozilla-central&lineNumber=6757 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | [started test2-seek-short.webm-11 t=2.358] Length of array should match number of running tests 10:58:46 INFO - test2-seek-short.ogv-10: got ended 10:58:46 INFO - test2-seek-short.ogv-10: got emptied 10:58:46 INFO - test2-seek-short.webm-11: got play 10:58:46 INFO - test2-seek-short.webm-11: got waiting 10:58:46 INFO - test2-seek-short.webm-11: got loadstart 10:58:46 INFO - test2-seek-short.webm-11: got suspend 10:58:46 INFO - test2-seek-short.webm-11: got loadedmetadata 10:58:46 INFO - test2-seek-short.webm-11: got loadeddata 10:58:46 INFO - test2-seek-short.webm-11: got canplay 10:58:46 INFO - test2-seek-short.webm-11: got playing 10:58:46 INFO - test2-seek-short.webm-11: got canplaythrough 10:58:46 INFO - test2-seek-short.webm-11: got pause 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | test2-seek-short.webm-11: played.length must be 1 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | test2-seek-short.webm-11: start time shall be 0 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | test2-seek-short.webm-11: end time shall be duration 10:58:46 INFO - [finished test2-seek-short.webm-11] remaining= test2-big-short.wav-9 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | [finished test2-seek-short.webm-11 t=2.64] Length of array should match number of running tests 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | [started test2-gizmo-short.mp4-12 t=2.641] Length of array should match number of running tests 10:58:46 INFO - test2-seek-short.webm-11: got ended 10:58:46 INFO - test2-seek-short.webm-11: got emptied 10:58:46 INFO - test2-gizmo-short.mp4-12: got play 10:58:46 INFO - test2-gizmo-short.mp4-12: got waiting 10:58:46 INFO - test2-gizmo-short.mp4-12: got loadstart 10:58:46 INFO - test2-gizmo-short.mp4-12: got suspend 10:58:46 INFO - test2-gizmo-short.mp4-12: got loadedmetadata 10:58:46 INFO - test2-gizmo-short.mp4-12: got loadeddata 10:58:46 INFO - test2-gizmo-short.mp4-12: got canplay 10:58:46 INFO - test2-gizmo-short.mp4-12: got playing 10:58:46 INFO - test2-gizmo-short.mp4-12: got canplaythrough 10:58:46 INFO - Buffered messages finished 10:58:46 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_played.html | Test timed out! 10:58:46 INFO - onTimeout@dom/media/test/manifest.js:1777:7 10:58:46 INFO - setTimeout handler*MediaTestManager/this.started@dom/media/test/manifest.js:1784:26 10:58:46 INFO - startTest@dom/media/test/test_played.html:223:3 10:58:46 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:46 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:46 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:46 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:46 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:46 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:46 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:46 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:46 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:46 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:46 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:46 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:46 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:46 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:46 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:46 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:46 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:46 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:46 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:46 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:46 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:46 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:46 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:46 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:46 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:46 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:46 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:46 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:46 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:46 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:46 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:46 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1753:7 10:58:46 INFO - test2-big-short.wav-9 timed out! 10:58:46 INFO - [finished test2-big-short.wav-9] remaining= test2-gizmo-short.mp4-12 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | [finished test2-big-short.wav-9 t=180.829] Length of array should match number of running tests 10:58:46 INFO - TEST-PASS | dom/media/test/test_played.html | [started test2-owl-short.mp3-13 t=180.832] Length of array should match number of running tests 10:58:46 INFO - test2-owl-short.mp3-13: got play 10:58:46 INFO - test2-owl-short.mp3-13: got waiting 10:58:46 INFO - test2-owl-short.mp3-13: got loadstart 10:58:46 INFO - test2-owl-short.mp3-13: got suspend 10:58:46 INFO - test2-owl-short.mp3-13: got loadedmetadata 10:58:46 INFO - test2-owl-short.mp3-13: got loadeddata 10:58:46 INFO - test2-owl-short.mp3-13: got canplay 10:58:46 INFO - test2-owl-short.mp3-13: got playing 10:58:46 INFO - test2-owl-short.mp3-13: got canplaythrough 10:58:46 INFO - GECKO(3668) | [Child 9176, MediaPDecoder #4] WARNING: Unimplemented function NotifyDataArrived: file z:/build/build/src/dom/media/mp3/MP3Demuxer.cpp, line 87 10:58:47 INFO - Not taking screenshot here: see the one that was previously logged 10:58:47 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_played.html | Test timed out! 10:58:47 INFO - onTimeout@dom/media/test/manifest.js:1777:7 10:58:47 INFO - setTimeout handler*MediaTestManager/this.started@dom/media/test/manifest.js:1784:26 10:58:47 INFO - startTest@dom/media/test/test_played.html:223:3 10:58:47 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:47 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:47 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:47 INFO - check_full_file_played/<@dom/media/test/test_played.html:28:5 10:58:47 INFO - EventListener.handleEvent*check_full_file_played@dom/media/test/test_played.html:23:3 10:58:47 INFO - setup@dom/media/test/test_played.html:46:5 10:58:47 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:47 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:47 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:47 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:47 INFO - check_full_file_played/<@dom/media/test/test_played.html:28:5 10:58:47 INFO - EventListener.handleEvent*check_full_file_played@dom/media/test/test_played.html:23:3 10:58:47 INFO - setup@dom/media/test/test_played.html:46:5 10:58:47 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:47 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:47 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:47 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:47 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:47 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:47 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:47 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:47 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:47 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:47 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:47 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:47 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:47 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:47 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:47 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:47 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:47 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:47 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:47 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:47 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1812:7 10:58:47 INFO - finish_test@dom/media/test/test_played.html:18:3 10:58:47 INFO - setup/<@dom/media/test/test_played.html:38:7 10:58:47 INFO - EventListener.handleEvent*setup@dom/media/test/test_played.html:36:5 10:58:47 INFO - startTest@dom/media/test/test_played.html:222:3 10:58:47 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1833:7 10:58:47 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1753:7 10:58:47 INFO - test2-gizmo-short.mp4-12 timed out! Nils, can you provide input here?
Flags: needinfo?(drno)

This seems to have stopped.

Status: ASSIGNED → RESOLVED
Closed: 6 years ago
Flags: needinfo?(drno)
Resolution: --- → WORKSFORME

This has popped up again.
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=256847104&repo=autoland&lineNumber=8378

03:08:55 INFO - TEST-PASS | dom/media/test/test_played.html | [finished test2-seek-short.webm-11 t=2.011] Length of array should match number of running tests
03:08:55 INFO - TEST-PASS | dom/media/test/test_played.html | [started test2-gizmo-short.mp4-12 t=2.012] Length of array should match number of running tests
03:08:55 INFO - test2-seek-short.webm-11: got ended
03:08:55 INFO - test2-seek-short.webm-11: got emptied
03:08:55 INFO - test2-gizmo-short.mp4-12: got play
03:08:55 INFO - test2-gizmo-short.mp4-12: got waiting
03:08:55 INFO - test2-gizmo-short.mp4-12: got loadstart
03:08:55 INFO - test2-gizmo-short.mp4-12: got suspend
03:08:55 INFO - test2-gizmo-short.mp4-12: got loadedmetadata
03:08:55 INFO - test2-gizmo-short.mp4-12: got loadeddata
03:08:55 INFO - test2-gizmo-short.mp4-12: got canplay
03:08:55 INFO - test2-gizmo-short.mp4-12: got playing
03:08:55 INFO - test2-gizmo-short.mp4-12: got canplaythrough
03:08:55 INFO - Buffered messages finished
03:08:55 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_played.html | Test timed out!
03:08:55 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
03:08:55 INFO - onTimeout@dom/media/test/manifest.js:1759:7
03:08:55 INFO - setTimeout handlerMediaTestManager/this.started@dom/media/test/manifest.js:1766:57
03:08:55 INFO - startTest@dom/media/test/test_played.html:223:11
03:08:55 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1815:12
03:08:55 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1794:12
03:08:55 INFO - finish_test@dom/media/test/test_played.html:18:11
03:08:55 INFO - setup/<@dom/media/test/test_played.html:38:7
03:08:55 INFO - EventListener.handleEventsetup@dom/media/test/test_played.html:36:13
03:08:55 INFO - startTest@dom/media/test/test_played.html:222:8
03:08:55 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1815:12
03:08:55 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1794:12
03:08:55 INFO - finish_test@dom/media/test/test_played.html:18:11
03:08:55 INFO - setup/<@dom/media/test/test_played.html:38:7
03:08:55 INFO - EventListener.handleEventsetup@dom/media/test/test_played.html:36:13
03:08:55 INFO - startTest@dom/media/test/test_played.html:222:8
03:08:55 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1815:12
03:08:55 INFO - MediaTestManager/this.finished@dom/media/test/manifest.js:1794:12
03:08:55 INFO - finish_test@dom/media/test/test_played.html:18:11
03:08:55 INFO - setup/<@dom/media/test/test_played.html:38:7
03:08:55 INFO - EventListener.handleEventsetup@dom/media/test/test_played.html:36:13
03:08:55 INFO - startTest@dom/media/test/test_played.html:222:8
03:08:55 INFO - MediaTestManager/this.nextTest@dom/media/test/manifest.js:1815:12
03:08:55 INFO - MediaTestManager/this.runTests/<@dom/media/test/manifest.js:1735:12
03:08:55 INFO - AsyncMediaTestManager/this.runTests@dom/media/test/manifest.js:1734:19
03:08:55 INFO - @dom/media/test/test_played.html:240:9
03:08:55 INFO - test2-big-short.wav-9 timed out!
03:08:55 INFO - [finished test2-big-short.wav-9] remaining= test2-gizmo-short.mp4-12
03:08:55 INFO - TEST-PASS | dom/media/test/test_played.html | [finished test2-big-short.wav-9 t=180.438] Length of array should match number of running tests
03:08:55 INFO - TEST-PASS | dom/media/test/test_played.html | [started test2-owl-short.mp3-13 t=180.441] Length of array should match number of running tests
03:08:55 INFO - test2-owl-short.mp3-13: got play
03:08:55 INFO - test2-owl-short.mp3-13: got waiting
03:08:55 INFO - test2-owl-short.mp3-13: got loadstart
03:08:55 INFO - test2-owl-short.mp3-13: got loadedmetadata
03:08:55 INFO - test2-owl-short.mp3-13: got suspend
03:08:55 INFO - test2-owl-short.mp3-13: got loadeddata
03:08:55 INFO - test2-owl-short.mp3-13: got canplay
03:08:55 INFO - test2-owl-short.mp3-13: got playing
03:08:55 INFO - test2-owl-short.mp3-13: got canplaythrough
03:08:55 INFO - GECKO(5468) | [Child 10988, MediaPDecoder #4] WARNING: Unimplemented function NotifyDataArrived: file z:/build/build/src/dom/media/mp3/MP3Demuxer.cpp, line 72
03:08:56 INFO - Not taking screenshot here: see the one that was previously logged
03:08:56 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_played.html | Test timed out!
03:08:56 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
03:08:56 INFO - onTimeout@dom/media/test/manifest.js:1759:7
03:08:56 INFO - setTimeout handlerMediaTestManager/this.started@dom/media/test/manifest.js:1766:57
03:08:56 INFO - startTest@dom/media/test/test_played.html:223:11

Status: RESOLVED → REOPENED
Resolution: WORKSFORME → ---

Bugbug thinks this bug is a regression, but please revert this change in case of error.

Keywords: regression

The bug assignee didn't login in Bugzilla in the last 7 months.
:jimm, could you have a look please?
For more information, please visit auto_nag documentation.

Assignee: suro001 → nobody
Flags: needinfo?(jmathies)
Flags: needinfo?(jmathies)
Severity: normal → S3
Keywords: intermittent-testcase
Summary: Intermittent test_played.html | Test timed out. → Intermittent dom/media/test/test_played.html | single tracking bug
Duplicate of this bug: 1613946

Update:

There have been 76 total failures within the last 7 days:

  • 2 failures on Android 13.0 Pixel5 AArch64 debug
  • 2 failures on Linux 18.04 x64 WebRender Shippable opt
  • 52 failures on OS X 10.15 WebRender debug/ opt
  • 3 failures on OS X 10.15 WebRender Shippable opt
  • 9 failures on OS X 11 WebRender Shippable opt
  • 3 failures on Windows 11 x86 22H2 WebRender debug
  • 2 failures on Windows 11 x86 22H2 WebRender Shippable opt
  • 2 failures on Windows 11 x64 22H2 asan WebRender opt
  • 1 failure on Windows 11 x64 22H2 WebRender Shippable opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=419740729&repo=autoland&lineNumber=3166

[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_played.html | After seeking, played.length should be 2 - got 1, expected 2
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:507:14
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     setup/<@dom/media/test/test_played.html:214:11
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:207:13
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     setup/<@dom/media/test/test_played.html:234:18
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:220:13
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     setup/<@dom/media/test/test_played.html:234:18
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:220:13
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     end@dom/media/test/test_played.html:142:18
[task 2023-06-18T00:11:00.907Z] 00:11:00     INFO -     onTimeUpdate_end@dom/media/test/test_played.html:166:9
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     EventListener.handleEvent*onTimeUpdate_pauseseekrestart@dom/media/test/test_played.html:159:17
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:170:13
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     end@dom/media/test/test_played.html:142:18
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     onTimeUpdate_end@dom/media/test/test_played.html:166:9
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     EventListener.handleEvent*onTimeUpdate_pauseseekrestart@dom/media/test/test_played.html:159:17
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:170:13
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     end@dom/media/test/test_played.html:142:18
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     onTimeUpdate_end@dom/media/test/test_played.html:166:9
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     EventListener.handleEvent*onTimeUpdate_pauseseekrestart@dom/media/test/test_played.html:159:17
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:170:13
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.908Z] 00:11:00     INFO -     end@dom/media/test/test_played.html:142:18
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     onTimeUpdate_end@dom/media/test/test_played.html:166:9
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     EventListener.handleEvent*onTimeUpdate_pauseseekrestart@dom/media/test/test_played.html:159:17
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:170:13
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     setup/<@dom/media/test/test_played.html:125:18
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:118:13
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     MediaTestManager/this.nextTest@dom/media/test/manifest.js:2461:12
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     MediaTestManager/this.finished@dom/media/test/manifest.js:2433:12
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     finish_test@dom/media/test/test_played.html:18:11
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     setup/<@dom/media/test/test_played.html:125:18
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     EventListener.handleEvent*setup@dom/media/test/test_played.html:118:13
[task 2023-06-18T00:11:00.909Z] 00:11:00     INFO -     startTest@dom/media/test/test_played.html:265:8
[task 2023-06-18T00:11:00.910Z] 00:11:00     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-06-18T00:11:00.914Z] 00:11:00     INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_played.html | After seeking, played.length should be 2 - got 1, expected 2
Whiteboard: [stockwell needswork:owner]

Jim, can you assign this to someone?
Thank you.

Flags: needinfo?(jmathies)
Depends on: 1758536
Assignee: nobody → ncsoregi
Attachment #9340620 - Attachment description: Bug 1110922 - Disabled dom/media/test/test_played.html on MacOS to reduce the extremely high failure count. r?#intermittent-reviewers → Bug 1110922 - Disable dom/media/test/test_played.html on MacOS to reduce the extremely high failure count. r?#intermittent-reviewers
Assignee: ncsoregi → nobody
Severity: S3 → --
Status: REOPENED → NEW
Keywords: leave-open
Priority: P5 → --
Attachment #9340620 - Attachment description: Bug 1110922 - Disable dom/media/test/test_played.html on MacOS to reduce the extremely high failure count. r?#intermittent-reviewers → Bug 1110922 - Disable dom/media/test/test_played.html on multiple platforms to reduce the high failure count. r?#intermittent-reviewers
Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/a998c42399a8 Disable dom/media/test/test_played.html on multiple platforms to reduce the high failure count. r=intermittent-reviewers,ahal
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]

I'm going to modify this to only disable the assertion that fails, and not the entire test on three tier-1 platform.

Flags: needinfo?(jmathies)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: