Closed Bug 1091155 Opened 11 years ago Closed 10 years ago

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

Categories

(Core :: Audio/Video, defect)

33 Branch
x86_64
Linux
defect
Not set
normal

Tracking

()

RESOLVED FIXED
mozilla40
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)

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+
Status: ASSIGNED → RESOLVED
Closed: 10 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla40
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: