985233 - Intermittent test_paused_after_ended.html | [finished 320x240.ogv-4] Length of array should match number of running tests - got 0, expected 1 | followed by several similar failures for other media files

Status: RESOLVED WORKSFORME

(Core :: Audio/Video, defect)

Description

[Wes Kocher (:KWierso) (Not reading bugmail; email directly if needed)]

https://tbpl.mozilla.org/php/getParsedLog.php?id=36349461&tree=B2g-Inbound
slave: tst-linux64-spot-723



12:01:07     INFO -  1417 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | seek.webm must be paused at start
12:01:07     INFO -  1418 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | seek.webm must not be paused after play
12:01:07     INFO -  1419 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:07     INFO -  1420 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | 320x240.ogv must be paused after end
12:01:07     INFO -  1421 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | [finished 320x240.ogv-4] Length of array should match number of running tests
12:01:07     INFO -  1422 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:07     INFO -  1423 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | 320x240.ogv must be paused after end
12:01:07     INFO -  1424 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [finished 320x240.ogv-4] Length of array should match number of running tests - got 0, expected 1
12:01:13     INFO -  1425 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [started vp9.webm-6] Length of array should match number of running tests - got 1, expected 2
12:01:14     INFO -  1426 INFO TEST-INFO | dumping last 4 message(s)
12:01:14     INFO -  1427 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
12:01:14     INFO -  1428 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | vp9.webm must be paused at start
12:01:14     INFO -  1429 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | vp9.webm must not be paused after play
12:01:14     INFO -  1430 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:14     INFO -  1431 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | seek.webm must be paused after end
12:01:14     INFO -  1432 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [finished seek.webm-5] Length of array should match number of running tests - got 0, expected 1
12:01:16     INFO -  1433 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [started detodos.opus-7] Length of array should match number of running tests - got 1, expected 2
12:01:16     INFO -  1434 INFO TEST-INFO | dumping last 4 message(s)
12:01:16     INFO -  1435 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
12:01:16     INFO -  1436 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | detodos.opus must be paused at start
12:01:16     INFO -  1437 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | detodos.opus must not be paused after play
12:01:17     INFO -  1438 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:17     INFO -  1439 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | vp9.webm must be paused after end
12:01:17     INFO -  1440 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [finished vp9.webm-6] Length of array should match number of running tests - got 0, expected 1
12:01:19     INFO -  1441 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [started gizmo.mp4-8] Length of array should match number of running tests - got 1, expected 2
12:01:20     INFO -  1442 INFO TEST-INFO | dumping last 4 message(s)
12:01:20     INFO -  1443 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
12:01:20     INFO -  1444 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | gizmo.mp4 must be paused at start
12:01:20     INFO -  1445 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | gizmo.mp4 must not be paused after play
12:01:20     INFO -  1446 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:20     INFO -  1447 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | detodos.opus must be paused after end
12:01:20     INFO -  1448 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [finished detodos.opus-7] Length of array should match number of running tests - got 0, expected 1
12:01:25     INFO -  1449 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [started r11025_u8_c1.wav-10] Length of array should match number of running tests - got 1, expected 2
12:01:27     INFO -  1450 INFO TEST-INFO | dumping last 4 message(s)
12:01:27     INFO -  1451 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
12:01:27     INFO -  1452 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | r11025_u8_c1.wav must be paused at start
12:01:27     INFO -  1453 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | r11025_u8_c1.wav must not be paused after play
12:01:27     INFO -  1454 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:27     INFO -  1455 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | r11025_u8_c1.wav must be paused after end
12:01:27     INFO -  1456 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [finished r11025_u8_c1.wav-10] Length of array should match number of running tests - got 0, expected 1
12:01:33     INFO -  1457 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [started small-shot.ogg-11] Length of array should match number of running tests - got 1, expected 2
12:01:33     INFO -  1458 INFO TEST-INFO | dumping last 4 message(s)
12:01:33     INFO -  1459 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
12:01:33     INFO -  1460 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | small-shot.ogg must be paused at start
12:01:33     INFO -  1461 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | small-shot.ogg must not be paused after play
12:01:34     INFO -  1462 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:34     INFO -  1463 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | small-shot.ogg must be paused after end
12:01:34     INFO -  1464 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [finished small-shot.ogg-11] Length of array should match number of running tests - got 0, expected 1
12:01:38     INFO -  1465 INFO TEST-INFO | dumping last 2 message(s)
12:01:38     INFO -  1466 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
12:01:38     INFO -  1467 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | We should have received a "pause" event.
12:01:38     INFO -  1468 INFO TEST-PASS | /tests/content/media/test/test_paused_after_ended.html | gizmo.mp4 must be paused after end
12:01:38     INFO -  1469 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_paused_after_ended.html | [finished gizmo.mp4-8] Length of array should match number of running tests - got -1, expected 0
12:01:40     INFO -  1470 INFO TEST-INFO | MEMORY STAT vsize after test: 125837312
12:01:40     INFO -  1471 INFO TEST-INFO | MEMORY STAT residentFast after test: 57610240
12:01:40     INFO -  1472 INFO TEST-INFO | MEMORY STAT heapAllocated after test: 24292844
12:01:40     INFO -  1473 INFO TEST-END | /tests/content/media/test/test_paused_after_ended.html | finished in 43962ms
12:01:40     INFO -  1474 INFO TEST-START | /tests/content/media/test/test_play_events.html

Comment 1

[JW Wang [:jwwang] [:jw_wang]]

It looks like the same cause as Bug 907162.

Comment 2

[Ed Morley [:emorley]]

Inactive; closing (see bug 1180138).