Closed Bug 1091155 Opened 10 years ago Closed 9 years ago

Intermittent test_eme_playback.html | gizmo-frag-cencinit.mp4-1 playing event should have fired

Categories

(Core :: Audio/Video, defect)

Product:
Core
Component:
Audio/Video
Version:
33 Branch
Platform:
x86_64
Linux
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla40
Tracking Flags:
Tracking Status
firefox37 --- wontfix
firefox38 --- fixed
firefox38.0.5 --- fixed
firefox39 --- fixed
firefox40 --- fixed
firefox-esr31 --- unaffected
b2g-v2.2 --- fixed
b2g-master --- fixed

People

(Reporter: RyanVM, Assigned: jwwang)

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

1091155_remove_checking_playing_event-v1.patch
1.82 KB, patch
cpearce
: review+
Details | Diff | Splinter Review 
08:56:49     INFO -  2215 INFO TEST-START | /tests/dom/media/test/test_eme_playback.html
08:56:49     INFO -  E/MPEG4Extractor( 1862): No width or height, assuming worst case 1080p
08:56:49     INFO -  I/SampleTable( 1862): There are reordered frames present.
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:51     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:52     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:52     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:52     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:52     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:52     INFO -  W/MPEG4Extractor( 1862): deferring processing of saiz box
08:56:52     INFO -  W/MPEG4Extractor( 1862): deferring processing of saio box
08:56:53     INFO -  2216 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | Invalid type.
08:56:53     INFO -  2217 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey supported.
08:56:53     INFO -  2218 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey bogus initDataType not supported.
08:56:53     INFO -  2219 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey/cenc should be supported.
08:56:53     INFO -  2220 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey/cenc bogus content type should be supported.
08:56:53     INFO -  2221 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey/cenc video/mp4 supported.
08:56:53     INFO -  2222 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey/cenc H.264/AAC supported.
08:56:53     INFO -  2223 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey/cenc audio/mp4 supported.
08:56:53     INFO -  2224 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | ClearKey/cenc AAC LC supported.
08:56:53     INFO -  2225 INFO Started Wed Oct 29 2014 08:56:49 GMT-0700 (PDT) (1414598209.544s)
08:56:53     INFO -  2226 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | [started short-cenc.mp4-0] Length of array should match number of running tests
08:56:53     INFO -  2227 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | [started gizmo-frag-cencinit.mp4-1] Length of array should match number of running tests
08:56:53     INFO -  2228 INFO fetching resource gizmo-frag-cencinit.mp4
08:56:53     INFO -  2229 INFO gizmo-frag-cencinit.mp4-1 got encrypted event
08:56:53     INFO -  2230 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 MediaKeys should support this keysystem
08:56:53     INFO -  2231 INFO short-cenc.mp4-0 got encrypted event
08:56:53     INFO -  2232 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 MediaKeys should support this keysystem
08:56:53     INFO -  2233 INFO fetching resource gizmo-frag-cenc1.m4s
08:56:53     INFO -  2234 INFO fetching resource gizmo-frag-cenc2.m4s
08:56:53     INFO -  2235 INFO gizmo-frag-cencinit.mp4-1 created MediaKeys object ok
08:56:53     INFO -  2236 INFO gizmo-frag-cencinit.mp4-1 set MediaKeys on <video> element ok
08:56:53     INFO -  2237 INFO Set 7e571d037e571d037e571d037e571d03 to false in session.keyIdsReceived
08:56:53     INFO -  2238 INFO Set 7e571d047e571d047e571d047e571d04 to false in session.keyIdsReceived
08:56:53     INFO -  2239 INFO short-cenc.mp4-0 created MediaKeys object ok
08:56:53     INFO -  2240 INFO short-cenc.mp4-0 set MediaKeys on <video> element ok
08:56:53     INFO -  2241 INFO Set 7e571d017e571d017e571d017e571d01 to false in session.keyIdsReceived
08:56:53     INFO -  2242 INFO Set 7e571d027e571d027e571d027e571d02 to false in session.keyIdsReceived
08:56:53     INFO -  2243 INFO got message from CDM: { "kids":["flcdA35XHQN-Vx0DflcdAw","flcdBH5XHQR-Vx0EflcdBA"], "type":"temporary"}
08:56:53     INFO -  2244 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | Key session type should match
08:56:53     INFO -  2245 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | message event should contain key ID array
08:56:53     INFO -  2246 INFO found key 7e5733337e5733337e5733337e573333 for key id 7e571d037e571d037e571d037e571d03
08:56:53     INFO -  2247 INFO found key 7e5744447e5744447e5744447e574444 for key id 7e571d047e571d047e571d047e571d04
08:56:53     INFO -  2248 INFO sending update message to CDM: {"keys":[{"kty":"oct","alg":"A128KW","kid":"flcdA35XHQN-Vx0DflcdAw","k":"flczM35XMzN-VzMzflczMw"},{"kty":"oct","alg":"A128KW","kid":"flcdBH5XHQR-Vx0EflcdBA","k":"fldERH5XRER-V0REfldERA"}],"type":"temporary"}
08:56:53     INFO -  2249 INFO got message from CDM: { "kids":["flcdAX5XHQF-Vx0BflcdAQ","flcdAn5XHQJ-Vx0CflcdAg"], "type":"temporary"}
08:56:53     INFO -  2250 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | Key session type should match
08:56:53     INFO -  2251 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | message event should contain key ID array
08:56:53     INFO -  2252 INFO found key 7e5711117e5711117e5711117e571111 for key id 7e571d017e571d017e571d017e571d01
08:56:53     INFO -  2253 INFO found key 7e5722227e5722227e5722227e572222 for key id 7e571d027e571d027e571d027e571d02
08:56:53     INFO -  2254 INFO sending update message to CDM: {"keys":[{"kty":"oct","alg":"A128KW","kid":"flcdAX5XHQF-Vx0BflcdAQ","k":"flcREX5XERF-VxERflcREQ"},{"kty":"oct","alg":"A128KW","kid":"flcdAn5XHQJ-Vx0CflcdAg","k":"flciIn5XIiJ-VyIiflciIg"}],"type":"temporary"}
08:56:53     INFO -  2255 INFO MediaKeySession update ok!
08:56:53     INFO -  2256 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 session.keyIdsReceived contained 7e571d037e571d037e571d037e571d03 as expected.
08:56:53     INFO -  2257 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 session.keyIdsReceived contained 7e571d047e571d047e571d047e571d04 as expected.
08:56:53     INFO -  2258 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 session.keyIdsReceived contained 7e571d037e571d037e571d037e571d03 as expected.
08:56:53     INFO -  2259 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 session.keyIdsReceived contained 7e571d047e571d047e571d047e571d04 as expected.
08:56:53     INFO -  2260 INFO MediaKeySession update ok!
08:56:53     INFO -  2261 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 session.keyIdsReceived contained 7e571d017e571d017e571d017e571d01 as expected.
08:56:53     INFO -  2262 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 session.keyIdsReceived contained 7e571d027e571d027e571d027e571d02 as expected.
08:56:53     INFO -  2263 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 session.keyIdsReceived contained 7e571d017e571d017e571d017e571d01 as expected.
08:56:53     INFO -  2264 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 session.keyIdsReceived contained 7e571d027e571d027e571d027e571d02 as expected.
08:56:53     INFO -  2265 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 got ended event
08:56:53     INFO -  2266 INFO [finished short-cenc.mp4-0] remaining= gizmo-frag-cencinit.mp4-1
08:56:53     INFO -  2267 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | [finished short-cenc.mp4-0] Length of array should match number of running tests
08:56:53     INFO -  2268 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 encrypted event should have fired
08:56:53     INFO -  2269 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 playing event should have fired
08:56:53     INFO -  2270 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 Duration of video should be corrrect
08:56:53     INFO -  2271 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 Current time should be same as duration
08:56:53     INFO -  2272 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | should have 1 session
08:56:53     INFO -  2273 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 should have received at least one keychange event
08:56:53     INFO -  2274 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 key with id 7e571d017e571d017e571d017e571d01 was usable as expected
08:56:53     INFO -  2275 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | short-cenc.mp4-0 key with id 7e571d027e571d027e571d027e571d02 was usable as expected
08:56:53     INFO -  2276 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 got ended event
08:56:53     INFO -  2277 INFO [finished gizmo-frag-cencinit.mp4-1] remaining=
08:56:53     INFO -  2278 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | [finished gizmo-frag-cencinit.mp4-1] Length of array should match number of running tests
08:56:53     INFO -  2279 INFO TEST-PASS | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 encrypted event should have fired
08:56:53     INFO -  2280 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/test/test_eme_playback.html | gizmo-frag-cencinit.mp4-1 playing event should have fired - expected PASS
08:56:54     INFO -  2281 INFO TEST-OK | /tests/dom/media/test/test_eme_playback.html | took 5050ms
Comment 45 is a different failure mode. Spun off to bug 1114705 rather than conflating it with this one.
Should we remove checking the 'playing' event? Since 'playing' depends on how fast we can decode, it is easy to miss on slow platforms like B2G emulator. I don't see much significance in checking 'playing' in the test case.
Flags: needinfo?(cpearce)
(In reply to JW Wang [:jwwang] from comment #59)
> Should we remove checking the 'playing' event? Since 'playing' depends on
> how fast we can decode, it is easy to miss on slow platforms like B2G
> emulator. I don't see much significance in checking 'playing' in the test
> case.

Sure... Listening on the "ended" event should suffice to prove we played.
Flags: needinfo?(cpearce)
Per comment 63, remove checking 'playing' event.
Assignee: nobody → jwwang
Status: NEW → ASSIGNED
Attachment #8595711 - Flags: review?(cpearce)
Attachment #8595711 - Flags: review?(cpearce) → review+
https://hg.mozilla.org/mozilla-central/rev/d3e10d7ea477
Status: ASSIGNED → RESOLVED
Closed: 9 years ago
status-firefox40: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla40
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: