1709010 - Intermittent dom/media/test/test_eme_autoplay.html | unexpected-crash-dump-found - This test left crash dumps behind, but we weren't expecting it to!

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=338487977&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TogPmZGCT-KI6AkhtrYgNw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-05-03T01:00:24.282Z] 01:00:24     INFO - TEST-START | dom/media/test/test_eme_autoplay.html
[task 2021-05-03T01:00:25.925Z] 01:00:25     INFO - GECKO(1739) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-05-03T01:00:25.925Z] 01:00:25     INFO - GECKO(1739) | MEMORY STAT | vsize 6575MB | residentFast 84MB | heapAllocated 9MB
[task 2021-05-03T01:00:25.930Z] 01:00:25     INFO - Buffered messages logged at 01:00:25
[task 2021-05-03T01:00:25.930Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | A valid string reason is expected 
[task 2021-05-03T01:00:25.932Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | Reason cannot be empty 
[task 2021-05-03T01:00:25.932Z] 01:00:25     INFO - Started Mon May 03 2021 01:00:25 GMT+0000 (Greenwich Mean Time) (1620003625.443s)
[task 2021-05-03T01:00:25.933Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | [started bipbop 10s-0 t=0.004] Length of array should match number of running tests 
[task 2021-05-03T01:00:25.933Z] 01:00:25     INFO - got loadstart event
[task 2021-05-03T01:00:25.936Z] 01:00:25     INFO - [01:00:25.488] bipbop 10s-0 sourceopen
[task 2021-05-03T01:00:25.936Z] 01:00:25     INFO - [01:00:25.489] bipbop 10s-0 video: addSourceBuffer(video/mp4; codecs="avc1.4d4015")
[task 2021-05-03T01:00:25.936Z] 01:00:25     INFO - [01:00:25.490] bipbop 10s-0 video: addNextFragment() fetching next fragment bipbop-cenc-video-10s.mp4
[task 2021-05-03T01:00:25.936Z] 01:00:25     INFO - [01:00:25.592] bipbop 10s-0 video: fetch of bipbop-cenc-video-10s.mp4 complete, appending
[task 2021-05-03T01:00:25.936Z] 01:00:25     INFO - got durationchange event
[task 2021-05-03T01:00:25.938Z] 01:00:25     INFO - got loadedmetadata event
[task 2021-05-03T01:00:25.938Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | [01:00:25.599] bipbop 10s-0 isEncrypted should be true 
[task 2021-05-03T01:00:25.938Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | isEncrypted should not be accessible from content 
[task 2021-05-03T01:00:25.938Z] 01:00:25     INFO - [01:00:25.602] bipbop 10s-0 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000) event.
[task 2021-05-03T01:00:25.939Z] 01:00:25     INFO - [01:00:25.603] bipbop 10s-0 video: updateend for bipbop-cenc-video-10s.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=0-10.01, audioTracks=-, videoTracks=-}
[task 2021-05-03T01:00:25.940Z] 01:00:25     INFO - [01:00:25.603] bipbop 10s-0 video: end of track
[task 2021-05-03T01:00:25.940Z] 01:00:25     INFO - [01:00:25.604] bipbop 10s-0 Tracks loaded, calling MediaSource.endOfStream()
[task 2021-05-03T01:00:25.941Z] 01:00:25     INFO - [01:00:25.860] bipbop 10s-0 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000)
[task 2021-05-03T01:00:25.942Z] 01:00:25     INFO - [01:00:25.861] bipbop 10s-0 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdA35XHQN-Vx0DflcdEQ","k":"flczM35XMzN-VzMzflczEQ"}],"type":"temporary"}
[task 2021-05-03T01:00:25.942Z] 01:00:25     INFO - [01:00:25.862] bipbop 10s-0 MediaKeySession update ok!
[task 2021-05-03T01:00:25.943Z] 01:00:25     INFO - [01:00:25.862] bipbop 10s-0 Updated all sessions, loading complete
[task 2021-05-03T01:00:25.943Z] 01:00:25     INFO - got loadeddata event
[task 2021-05-03T01:00:25.947Z] 01:00:25     INFO - got canplay event
[task 2021-05-03T01:00:25.947Z] 01:00:25     INFO - got play event
[task 2021-05-03T01:00:25.948Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | Must not have received event too early 
[task 2021-05-03T01:00:25.948Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | event should have only be fired once 
[task 2021-05-03T01:00:25.948Z] 01:00:25     INFO - got playing event
[task 2021-05-03T01:00:25.948Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | Must not have received event too early 
[task 2021-05-03T01:00:25.948Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | event should have only be fired once 
[task 2021-05-03T01:00:25.949Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | [01:00:25.890] bipbop 10s-0 got playing event 
[task 2021-05-03T01:00:25.949Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | Expected number 1 wait, got: 1 
[task 2021-05-03T01:00:25.949Z] 01:00:25     INFO - [finished bipbop 10s-0] remaining= 
[task 2021-05-03T01:00:25.950Z] 01:00:25     INFO - TEST-PASS | dom/media/test/test_eme_autoplay.html | [finished bipbop 10s-0 t=0.449] Length of array should match number of running tests 
[task 2021-05-03T01:00:25.951Z] 01:00:25     INFO - got abort event
[task 2021-05-03T01:00:25.951Z] 01:00:25     INFO - got emptied event
[task 2021-05-03T01:00:25.951Z] 01:00:25     INFO - Finished at Mon May 03 2021 01:00:25 GMT+0000 (Greenwich Mean Time) (1620003625.919s)
[task 2021-05-03T01:00:25.952Z] 01:00:25     INFO - Running time: 0.477s
[task 2021-05-03T01:00:25.952Z] 01:00:25     INFO - Buffered messages finished
[task 2021-05-03T01:00:25.953Z] 01:00:25     INFO - TEST-UNEXPECTED-ERROR | dom/media/test/test_eme_autoplay.html | unexpected-crash-dump-found - This test left crash dumps behind, but we weren't expecting it to!
[task 2021-05-03T01:00:25.954Z] 01:00:25     INFO - Found unexpected crash dump file /var/folders/yc/gg8285715nj938l1vm_4k8tm000014/T/tmp6czr33_3.mozrunner/minidumps/26F98F52-6588-401F-BC65-13DB581796B4.dmp.
[task 2021-05-03T01:00:25.954Z] 01:00:25     INFO - TEST-UNEXPECTED-CRASH | dom/media/test/test_eme_autoplay.html | Finished in 1653ms
[task 2021-05-03T01:00:25.954Z] 01:00:25     INFO - {'runtime': 1653}
[task 2021-05-03T01:00:25.954Z] 01:00:25     INFO - TEST-INFO took 1653ms```

Comment 1

[Intermittent Failures Robot]

5 failures in 3279 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3
• try: 1
• mozilla-central: 1

Platform and build breakdown:

• macosx1014-64-qr: 3
  • opt: 3
• macosx1014-64-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1709010&startday=2021-05-03&endday=2021-05-09&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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