1056629 - Intermittent test_seek-1.html | owl.mp3 seek test 1: Video currentTime should be around 1.645: 0.600806 (seeking) (and two more)

Status: RESOLVED WORKSFORME

(Core :: Audio/Video, defect)

Description

[Ed Morley [:emorley]]

b2g_emulator_vm mozilla-inbound opt test mochitest-3 on 2014-08-20 16:55:24 PDT for push 477785eeb00e

slave: tst-linux64-spot-563

https://tbpl.mozilla.org/php/getParsedLog.php?id=46416036&tree=Mozilla-Inbound

{
17:51:31     INFO -  1161 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | [started gizmo.mp4-8-seek1.js] Length of array should match number of running tests
17:51:31     INFO -  1162 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | split.webm seek test 1: Video currentTime should be around 0.9835: 0.9835 (seeked)
17:51:31     INFO -  1163 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | split.webm seek test 1: seeking event
17:51:31     INFO -  1164 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | split.webm seek test 1: seeked event
17:51:31     INFO -  1165 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | split.webm seek test 1: seeking flag on start should be true
17:51:31     INFO -  1166 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | split.webm seek test 1: seeking flag on end should be false
17:51:31     INFO -  1167 INFO [finished split.webm-6-seek1.js] remaining= gizmo.mp4-8-seek1.js
17:51:31     INFO -  1168 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | [finished split.webm-6-seek1.js] Length of array should match number of running tests
17:51:31     INFO -  1169 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | gizmo.mp4 seek test 1: seeking should default to false
17:51:31     INFO -  1170 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | gizmo.mp4 seek test 1: seeking should be readonly
17:51:31     INFO -  1171 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | [started owl.mp3-9-seek1.js] Length of array should match number of running tests
17:51:31     INFO -  1172 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | gizmo.mp4 seek test 1: Video currentTime should be around 2.78: 2.78 (seeking)
17:51:31     INFO -  1173 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: seeking should default to false
17:51:31     INFO -  1174 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: seeking should be readonly
17:51:31     INFO -  1175 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: Video currentTime should be around 1.645: 0.600806 (seeking)
17:51:31     INFO -  TEST-INFO | expected PASS
17:51:31     INFO -  1176 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: Video currentTime should be around 1.645: 0.600806 (seeked)
17:51:31     INFO -  TEST-INFO | expected PASS
17:51:31     INFO -  1177 INFO dumping last 3 message(s)
17:51:31     INFO -  1178 INFO if you need more context, please use SimpleTest.requestCompleteLog() in your test
17:51:31     INFO -  1179 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: seeking event
17:51:31     INFO -  1180 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: seeked event
17:51:31     INFO -  1181 INFO TEST-PASS | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: seeking flag on start should be true
17:51:31     INFO -  1182 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: seeking flag on end should be false
}

Logcat:
http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/294195f84e9c9b40e3bbf7974647e6ef0404b3836052031918282780e9768df3f4f1a209fe1facdd05a53310075d894e691689f91ef738d1fb8a684740e88269

Comment 1

[cmtalbert]

Chris, any idea on why this is now intermittent? I don't see much in the logcat near where the test fails, but I remember something about us loading artifacts very slowly on the b2g emulators. And that might be another avenue for exploration.

Ed - any idea on whether or not this is the same rough range of time that you originally saw when this was one giant test together? I wonder if it is the same issue, just now more contained or if it might be something new.

Comment 2

[Chris Pearce [:cpearce (Not reading bugmail)]]

(In reply to Clint Talbert ( :ctalbert ) from comment #1)
> Chris, any idea on why this is now intermittent? 

When did it become intermittent and on which platforms?

Comment 3

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=46517773&tree=Fx-Team
b2g_emulator_vm fx-team opt test mochitest-3 on 2014-08-21 17:11:22
revision: a8c1fe0aec46
slave: tst-linux64-spot-514

1221 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: Video currentTime should be around 1.645: 0.444074 (seeking)
1222 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: Video currentTime should be around 1.645: 0.444074 (seeked)
1228 INFO TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_seek-1.html | owl.mp3 seek test 1: seeking flag on end should be false
Return code: 1

Comment 4

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

See bug 1039901 and the bugs it blocks. This is a known bug on B2G.

Comment 5

[Legacy TBPL/Treeherder Robot]

submit_timestamp: 2014-11-10T01:04:17
log: https://treeherder.mozilla.org/ui/logviewer.html#?repo=mozilla-inbound&job_id=3727479
repository: mozilla-inbound
who: tomcat[at]mozilla[dot]com
machine: tst-linux64-spot-620
buildname: Android 2.3 Emulator mozilla-inbound opt test mochitest-10
revision: 961d11a08333

TEST-UNEXPECTED-FAIL | /tests/dom/media/test/test_seek-1.html | application timed out after 330 seconds with no output
PROCESS-CRASH | /tests/dom/media/test/test_seek-1.html | application crashed [@ libc.so + 0xc51c]