Closed Bug 1476780 Opened 6 years ago Closed 6 years ago

Intermittent dom/media/test/test_eme_playback.html | Test timed out!

Tracking

()

Status:

RESOLVED DUPLICATE of bug 1187903

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [TV-bf pass:pass])

Treeherder Bug Filer

Reporter

Description

•

6 years ago

treeherder

Filed by: shindli [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=188799520&repo=mozilla-central

https://queue.taskcluster.net/v1/task/J0_PtEZ-RL2jLRH34WI2bA/runs/0/artifacts/public/logs/live_backing.log

18:47:03    ERROR -  1333 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_eme_playback.html | Test timed out!
18:47:03     INFO -  onTimeout@https://example.com/tests/dom/media/test/manifest.js:1717:7
18:47:03     INFO -  setTimeout handler*MediaTestManager/this.started@https://example.com/tests/dom/media/test/manifest.js:1724:26
18:47:03     INFO -  startTest@https://example.com/tests/dom/media/test/test_eme_playback.html:77:3
18:47:03     INFO -  MediaTestManager/this.nextTest@https://example.com/tests/dom/media/test/manifest.js:1773:7
18:47:03     INFO -  MediaTestManager/this.finished@https://example.com/tests/dom/media/test/manifest.js:1752:7
18:47:03     INFO -  startTest/<@https://example.com/tests/dom/media/test/test_eme_playback.html:179:15
18:47:03     INFO -  1334 INFO 640x480@624kbps audio&video tracks, each with its key-6 timed out!
18:47:03     INFO -  1335 INFO [finished 640x480@624kbps audio&video tracks, each with its key-6] remaining= 640x480@959kbps audio&video tracks, each with its key-7
18:47:03     INFO -  1336 INFO TEST-PASS | dom/media/test/test_eme_playback.html | [finished 640x480@624kbps audio&video tracks, each with its key-6 t=186.508] Length of array should match number of running tests
18:47:03     INFO -  1337 INFO TEST-PASS | dom/media/test/test_eme_playback.html | [started 640x480 then 400x300, same key (1st) per track-8 t=186.512] Length of array should match number of running tests
18:47:03     INFO -  1338 INFO [18:47:03.159] 640x480 then 400x300, same key (1st) per track-8 sourceopen
18:47:03     INFO -  1339 INFO [18:47:03.160] 640x480 then 400x300, same key (1st) per track-8 audio: addSourceBuffer(audio/mp4; codecs="mp4a.40.2")
18:47:03     INFO -  1340 INFO [18:47:03.162] 640x480 then 400x300, same key (1st) per track-8 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-init.mp4
18:47:03     INFO -  1341 INFO [18:47:03.164] 640x480 then 400x300, same key (1st) per track-8 video: addSourceBuffer(video/mp4; codecs="avc1.64000d")
18:47:03     INFO -  1342 INFO [18:47:03.165] 640x480 then 400x300, same key (1st) per track-8 video: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-video-key1-init.mp4
18:47:03     INFO -  1343 INFO [18:47:03.212] 640x480 then 400x300, same key (1st) per track-8 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-init.mp4 complete, appending
18:47:03     INFO -  1344 INFO [18:47:03.218] 640x480 then 400x300, same key (1st) per track-8 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=-, audioTracks=-, videoTracks=-}
18:47:03     INFO -  1345 INFO [18:47:03.220] 640x480 then 400x300, same key (1st) per track-8 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-1.m4s
18:47:03     INFO -  1346 INFO [18:47:03.310] 640x480 then 400x300, same key (1st) per track-8 video: fetch of bipbop_480_624kbps-cenc-video-key1-init.mp4 complete, appending
18:47:03     INFO -  1347 INFO TEST-PASS | dom/media/test/test_eme_playback.html | [18:47:03.315] 640x480 then 400x300, same key (1st) per track-8 isEncrypted should be true
18:47:03     INFO -  1348 INFO TEST-PASS | dom/media/test/test_eme_playback.html | isEncrypted should not be accessible from content
18:47:03     INFO -  1349 INFO [18:47:03.319] 640x480 then 400x300, same key (1st) per track-8 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d047e571d047e571d047e571d2100000000) event.
18:47:03     INFO -  1350 INFO [18:47:03.323] 640x480 then 400x300, same key (1st) per track-8 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000) event.
18:47:03     INFO -  1351 INFO [18:47:03.325] 640x480 then 400x300, same key (1st) per track-8 video: updateend for bipbop_480_624kbps-cenc-video-key1-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=-, audioTracks=-, videoTracks=-}
18:47:03     INFO -  1352 INFO [18:47:03.326] 640x480 then 400x300, same key (1st) per track-8 video: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-video-key1-1.m4s
18:47:03     INFO -  1353 INFO [18:47:03.484] 640x480 then 400x300, same key (1st) per track-8 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-1.m4s complete, appending
18:47:03     INFO -  1354 INFO [18:47:03.486] 640x480 then 400x300, same key (1st) per track-8 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-1.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.464399, audioTracks=-, videoTracks=-}
18:47:03     INFO -  1355 INFO [18:47:03.488] 640x480 then 400x300, same key (1st) per track-8 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-2.m4s
18:47:03     INFO -  1356 INFO [18:47:03.629] 640x480 then 400x300, same key (1st) per track-8 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-2.m4s complete, appending
18:47:03     INFO -  1357 INFO [18:47:03.630] 640x480 then 400x300, same key (1st) per track-8 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-2.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.928798, audioTracks=-, videoTracks=-}
18:47:03     INFO -  1358 INFO [18:47:03.631] 640x480 then 400x300, same key (1st) per track-8 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-3.m4s
18:47:03     INFO -  1359 INFO [18:47:03.722] 640x480 then 400x300, same key (1st) per track-8 video: fetch of bipbop_480_624kbps-cenc-video-key1-1.m4s complete, appending
18:47:03     INFO -  1360 INFO [18:47:03.724] 640x480 then 400x300, same key (1st) per track-8 video: updateend for bipbop_480_624kbps-cenc-video-key1-1.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.801666, audioTracks=-, videoTracks=-}
18:47:03     INFO -  1361 INFO [18:47:03.725] 640x480 then 400x300, same key (1st) per track-8 video: addNextFragment() fetching next fragment bipbop_300_215kbps-cenc-video-key1-init.mp4
18:47:03     INFO -  1362 INFO [18:47:03.833] 640x480 then 400x300, same key (1st) per track-8 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-3.m4s complete, appending
18:47:03     INFO -  1363 INFO [18:47:03.834] 640x480 then 400x300, same key (1st) per track-8 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-3.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.393197, audioTracks=-, videoTracks=-}
18:47:03     INFO -  1364 INFO [18:47:03.835] 640x480 then 400x300, same key (1st) per track-8 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-4.m4s
18:47:03     INFO -  1365 INFO [18:47:03.925] 640x480 then 400x300, same key (1st) per track-8 video: fetch of bipbop_300_215kbps-cenc-video-key1-init.mp4 complete, appending
18:47:03     INFO -  1366 INFO [18:47:03.927] 640x480 then 400x300, same key (1st) per track-8 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000) event.
18:47:03     INFO -  1367 INFO [18:47:03.928] 640x480 then 400x300, same key (1st) per track-8 video: updateend for bipbop_300_215kbps-cenc-video-key1-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.801666, audioTracks=-, videoTracks=-}
18:47:03     INFO -  1368 INFO [18:47:03.928] 640x480 then 400x300, same key (1st) per track-8 video: addNextFragment() fetching next fragment bipbop_300_215kbps-cenc-video-key1-2.m4s
18:47:04     INFO -  1369 INFO [18:47:04.51]  640x480 then 400x300, same key (1st) per track-8 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-4.m4s complete, appending
18:47:04     INFO -  1370 INFO [18:47:04.53]  640x480 then 400x300, same key (1st) per track-8 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-4.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.578956, audioTracks=-, videoTracks=-}
18:47:04     INFO -  1371 INFO [18:47:04.53]  640x480 then 400x300, same key (1st) per track-8 audio: end of track
18:47:04     INFO -  1372 INFO [18:47:04.157] 640x480 then 400x300, same key (1st) per track-8 video: fetch of bipbop_300_215kbps-cenc-video-key1-2.m4s complete, appending
18:47:04     INFO -  1373 INFO [18:47:04.159] 640x480 then 400x300, same key (1st) per track-8 video: updateend for bipbop_300_215kbps-cenc-video-key1-2.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.601666, audioTracks=-, videoTracks=-}
18:47:04     INFO -  1374 INFO [18:47:04.160] 640x480 then 400x300, same key (1st) per track-8 video: end of track
18:47:04     INFO -  1375 INFO [18:47:04.161] 640x480 then 400x300, same key (1st) per track-8 Tracks loaded, calling MediaSource.endOfStream()
18:47:04     INFO -  1376 INFO [18:47:04.161] 640x480 then 400x300, same key (1st) per track-8 Set 7e571d037e571d037e571d037e571d11 to false in session[].keyIdsReceived
18:47:04     INFO -  1377 INFO [18:47:04.162] 640x480 then 400x300, same key (1st) per track-8 Set 7e571d047e571d047e571d047e571d21 to false in session[].keyIdsReceived
18:47:04     INFO -  1378 INFO [18:47:04.163] 640x480 then 400x300, same key (1st) per track-8 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d047e571d047e571d047e571d2100000000)
18:47:04     INFO -  1379 INFO [18:47:04.164] 640x480 then 400x300, same key (1st) per track-8 Set 7e571d037e571d037e571d037e571d11 to false in session[].keyIdsReceived
18:47:04     INFO -  1380 INFO [18:47:04.164] 640x480 then 400x300, same key (1st) per track-8 Set 7e571d047e571d047e571d047e571d21 to false in session[].keyIdsReceived
18:47:04     INFO -  1381 INFO [18:47:04.164] 640x480 then 400x300, same key (1st) per track-8 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000)
18:47:04     INFO -  1382 INFO [18:47:04.165] 640x480 then 400x300, same key (1st) per track-8 Set 7e571d037e571d037e571d037e571d11 to false in session[].keyIdsReceived
18:47:04     INFO -  1383 INFO [18:47:04.166] 640x480 then 400x300, same key (1st) per track-8 Set 7e571d047e571d047e571d047e571d21 to false in session[].keyIdsReceived
18:47:04     INFO -  1384 INFO [18:47:04.167] 640x480 then 400x300, same key (1st) per track-8 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000)
18:47:04     INFO -  1385 INFO [18:47:04.173] 640x480 then 400x300, same key (1st) per track-8 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdBH5XHQR-Vx0EflcdIQ","k":"fldERH5XRER-V0REfldEIQ"}],"type":"temporary"}
18:47:04     INFO -  1386 INFO [18:47:04.180] 640x480 then 400x300, same key (1st) per track-8 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdA35XHQN-Vx0DflcdEQ","k":"flczM35XMzN-VzMzflczEQ"}],"type":"temporary"}
18:47:04     INFO -  1387 INFO [18:47:04.181] 640x480 then 400x300, same key (1st) per track-8 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdA35XHQN-Vx0DflcdEQ","k":"flczM35XMzN-VzMzflczEQ"}],"type":"temporary"}
18:47:04     INFO -  1388 INFO [18:47:04.182] 640x480 then 400x300, same key (1st) per track-8 MediaKeySession update ok!
18:47:04     INFO -  1389 INFO [18:47:04.182] 640x480 then 400x300, same key (1st) per track-8 MediaKeySession update ok!
18:47:04     INFO -  1390 INFO TEST-PASS | dom/media/test/test_eme_playback.html | Shouldn't have a key that's not in the media
18:47:04     INFO -  1391 INFO TEST-PASS | dom/media/test/test_eme_playback.html | Unknown keys should undefined status
18:47:04     INFO -  1392 INFO TEST-PASS | dom/media/test/test_eme_playback.html | keyId should be ArrayBuffer
18:47:04     INFO -  1393 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.has() should work.
18:47:04     INFO -  1394 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.get() should work.
18:47:04     INFO -  1395 INFO TEST-PASS | dom/media/test/test_eme_playback.html | 640x480 then 400x300, same key (1st) per track-8: key status should be usable
18:47:04     INFO -  1396 INFO TEST-PASS | dom/media/test/test_eme_playback.html | [18:47:04.191] 640x480 then 400x300, same key (1st) per track-8 session[15].keyIdsReceived contained 7e571d047e571d047e571d047e571d21 as expected.
18:47:04     INFO -  1397 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.keys() should correspond to entries
18:47:04     INFO -  1398 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.values() should correspond to entries
18:47:04     INFO -  1399 INFO TEST-PASS | dom/media/test/test_eme_playback.html | Shouldn't have a key that's not in the media
18:47:04     INFO -  1400 INFO TEST-PASS | dom/media/test/test_eme_playback.html | Unknown keys should undefined status
18:47:04     INFO -  1401 INFO TEST-PASS | dom/media/test/test_eme_playback.html | keyId should be ArrayBuffer
18:47:04     INFO -  1402 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.has() should work.
18:47:04     INFO -  1403 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.get() should work.
18:47:04     INFO -  1404 INFO TEST-PASS | dom/media/test/test_eme_playback.html | 640x480 then 400x300, same key (1st) per track-8: key status should be usable
18:47:04     INFO -  1405 INFO TEST-PASS | dom/media/test/test_eme_playback.html | [18:47:04.200] 640x480 then 400x300, same key (1st) per track-8 session[16].keyIdsReceived contained 7e571d037e571d037e571d037e571d11 as expected.
18:47:04     INFO -  1406 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.keys() should correspond to entries
18:47:04     INFO -  1407 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.values() should correspond to entries
18:47:04     INFO -  1408 INFO [18:47:04.205] 640x480 then 400x300, same key (1st) per track-8 MediaKeySession update ok!
18:47:04     INFO -  1409 INFO TEST-PASS | dom/media/test/test_eme_playback.html | Shouldn't have a key that's not in the media
18:47:04     INFO -  1410 INFO TEST-PASS | dom/media/test/test_eme_playback.html | Unknown keys should undefined status
18:47:04     INFO -  1411 INFO TEST-PASS | dom/media/test/test_eme_playback.html | keyId should be ArrayBuffer
18:47:04     INFO -  1412 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.has() should work.
18:47:04     INFO -  1413 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.get() should work.
18:47:04     INFO -  1414 INFO TEST-PASS | dom/media/test/test_eme_playback.html | 640x480 then 400x300, same key (1st) per track-8: key status should be usable
18:47:04     INFO -  1415 INFO TEST-PASS | dom/media/test/test_eme_playback.html | [18:47:04.212] 640x480 then 400x300, same key (1st) per track-8 session[17].keyIdsReceived contained 7e571d037e571d037e571d037e571d11 as expected.
18:47:04     INFO -  1416 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.keys() should correspond to entries
18:47:04     INFO -  1417 INFO TEST-PASS | dom/media/test/test_eme_playback.html | MediaKeyStatusMap.values() should correspond to entries
18:47:04     INFO -  Not taking screenshot here: see the one that was previously logged
18:47:04    ERROR -  1418 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_eme_playback.html | Test timed out!

Joel Maher ( :jmaher ) (UTC -8)

Comment 1

•

6 years ago

unable to reproduce this error with test-verify:
https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&filter-searchStr=win%20qr%20-bf&tochange=10ca5b14dbdb6be329e3d47f4dcbc27b43ae1823&fromchange=8dab948a10f073a46f13f55f94d1f6514c7360ac

Whiteboard: [TV-bf pass:pass]

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Updated

•

6 years ago

Status: NEW → RESOLVED

Closed: 6 years ago

Resolution: --- → DUPLICATE

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1476780&startday=2018-07-16&endday=2018-07-22&tree=trunk

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Quick Search

Intermittent dom/media/test/test_eme_playback.html | Test timed out!

Categories

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

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [TV-bf pass:pass])

Crash Data

Security

(public)

User Story

Description

Comment 1

Updated

Comment 3