Closed
Bug 899074
Opened 11 years ago
Closed 10 years ago
Fix intermittent B2G timeouts during test_autoplay_contentEditable.html and re-enable it
Categories
(Core :: Audio/Video, defect)
Tracking
()
RESOLVED
WORKSFORME
People
(Reporter: RyanVM, Unassigned)
References
Details
(Keywords: intermittent-failure, Whiteboard: [test disabled])
Looks like this is happening at the same place in test_autoplay_contentEditable.html every time. First occurrence was on Friday in the log below. https://tbpl.mozilla.org/php/getParsedLog.php?id=25809780&tree=Fx-Team b2g_emulator_vm fx-team opt test mochitest-2 on 2013-07-26 21:38:26 PDT for push 4874fa438b1c slave: tst-linux64-ec2-310 22:01:41 INFO - 37168 INFO TEST-START | /tests/content/media/test/test_autoplay_contentEditable.html 22:01:41 INFO - 37169 INFO TEST-INFO | /tests/content/media/test/test_autoplay_contentEditable.html | Started Sat Jul 27 2013 05:01:40 GMT+0000 (GMT) (1374901300.373s) 22:01:41 INFO - 37170 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started small-shot.ogg-0] Length of array should match number of running tests 22:01:41 INFO - 37171 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started small-shot.m4a-1] Length of array should match number of running tests 22:01:41 INFO - 37172 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished small-shot.ogg-0] Length of array should match number of running tests 22:01:41 INFO - 37173 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started small-shot.mp3-2] Length of array should match number of running tests 22:01:41 INFO - 37174 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished small-shot.m4a-1] Length of array should match number of running tests 22:01:41 INFO - 37175 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started r11025_s16_c1.wav-3] Length of array should match number of running tests 22:01:41 INFO - 37176 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/small-shot.ogg uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 22:01:43 INFO - 37177 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/small-shot.ogg uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 22:01:43 INFO - 37178 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/small-shot.m4a uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 22:01:43 INFO - 37179 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/small-shot.m4a uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 22:01:43 INFO - 37180 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished small-shot.mp3-2] Length of array should match number of running tests 22:01:43 INFO - 37181 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started 320x240.ogv-4] Length of array should match number of running tests 22:01:43 INFO - 37182 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/r11025_s16_c1.wav uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 22:01:43 INFO - 37183 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/r11025_s16_c1.wav uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 22:01:43 INFO - 37184 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished r11025_s16_c1.wav-3] Length of array should match number of running tests 22:01:43 INFO - 37185 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started seek.webm-5] Length of array should match number of running tests 22:01:43 INFO - 37186 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/small-shot.mp3 uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 22:01:43 INFO - 37187 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/small-shot.mp3 uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 22:01:43 INFO - 37188 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished 320x240.ogv-4] Length of array should match number of running tests 22:01:43 INFO - 37189 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started detodos.opus-6] Length of array should match number of running tests 22:01:43 INFO - 37190 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished seek.webm-5] Length of array should match number of running tests 22:01:43 INFO - 37191 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started gizmo.mp4-7] Length of array should match number of running tests command timed out: 1200 seconds without output, attempting to kill process killed by signal 9 program finished with exit code -1
Reporter | ||
Comment 1•11 years ago
|
||
https://tbpl.mozilla.org/php/getParsedLog.php?id=25819518&tree=Mozilla-Inbound https://tbpl.mozilla.org/php/getParsedLog.php?id=25839524&tree=Birch https://tbpl.mozilla.org/php/getParsedLog.php?id=25857762&tree=Birch
Reporter | ||
Comment 2•11 years ago
|
||
From a passing run: https://tbpl.mozilla.org/php/getParsedLog.php?id=25855151&tree=Birch&full=1 04:51:52 INFO - 37168 INFO TEST-START | /tests/content/media/test/test_autoplay_contentEditable.html 04:51:52 INFO - 37169 INFO TEST-INFO | /tests/content/media/test/test_autoplay_contentEditable.html | Started Mon Jul 29 2013 11:51:50 GMT+0000 (GMT) (1375098710.858s) 04:51:52 INFO - 37170 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started small-shot.ogg-0] Length of array should match number of running tests 04:51:52 INFO - 37171 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started small-shot.m4a-1] Length of array should match number of running tests 04:51:52 INFO - 37172 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/small-shot.ogg uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 04:51:52 INFO - 37173 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/small-shot.ogg uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 04:51:52 INFO - 37174 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished small-shot.ogg-0] Length of array should match number of running tests 04:51:52 INFO - 37175 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started small-shot.mp3-2] Length of array should match number of running tests 04:51:54 INFO - 37176 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished small-shot.m4a-1] Length of array should match number of running tests 04:51:54 INFO - 37177 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started r11025_s16_c1.wav-3] Length of array should match number of running tests 04:51:54 INFO - 37178 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/small-shot.m4a uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 04:51:54 INFO - 37179 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/small-shot.m4a uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 04:51:54 INFO - 37180 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/r11025_s16_c1.wav uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 04:51:54 INFO - 37181 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/r11025_s16_c1.wav uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 04:51:54 INFO - 37182 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished small-shot.mp3-2] Length of array should match number of running tests 04:51:54 INFO - 37183 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started 320x240.ogv-4] Length of array should match number of running tests 04:51:54 INFO - 37184 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished r11025_s16_c1.wav-3] Length of array should match number of running tests 04:51:54 INFO - 37185 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started seek.webm-5] Length of array should match number of running tests 04:51:54 INFO - 37186 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/small-shot.mp3 uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 04:51:54 INFO - 37187 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/small-shot.mp3 uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 04:51:54 INFO - 37188 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished 320x240.ogv-4] Length of array should match number of running tests 04:51:54 INFO - 37189 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started detodos.opus-6] Length of array should match number of running tests 04:51:54 INFO - 37190 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished seek.webm-5] Length of array should match number of running tests 04:51:54 INFO - 37191 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [started gizmo.mp4-7] Length of array should match number of running tests 04:51:58 INFO - 37192 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/320x240.ogv uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 04:51:58 INFO - 37193 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/320x240.ogv uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 04:51:58 INFO - 37194 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/seek.webm uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 04:51:58 INFO - 37195 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/seek.webm uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 04:51:58 INFO - 37196 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished detodos.opus-6] Length of array should match number of running tests 04:51:58 INFO - 37197 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | [finished gizmo.mp4-7] Length of array should match number of running tests 04:51:58 INFO - 37198 INFO TEST-INFO | /tests/content/media/test/test_autoplay_contentEditable.html | Finished at Mon Jul 29 2013 11:51:54 GMT+0000 (GMT) (1375098714.632s) 04:51:58 INFO - 37199 INFO TEST-INFO | /tests/content/media/test/test_autoplay_contentEditable.html | Running time: 3.774s 04:51:58 INFO - 37200 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplay at 0 for http://mochi.test:8888/tests/content/media/test/detodos.opus uneval(event.type)="canplay" typeof(event.type)=string uneval(v._state)=0 typeof(v._state)=number tokens[0]=canplay tokens[0].indexOf(event.type)=0 04:51:58 INFO - 37201 INFO TEST-PASS | /tests/content/media/test/test_autoplay_contentEditable.html | Check expected event got canplaythrough at canplay for http://mochi.test:8888/tests/content/media/test/detodos.opus uneval(event.type)="canplaythrough" typeof(event.type)=string uneval(v._state)="canplay" typeof(v._state)=string tokens[canplay]=canplay,canplaythrough tokens[canplay].indexOf(event.type)=1 04:51:58 INFO - 37202 INFO TEST-END | /tests/content/media/test/test_autoplay_contentEditable.html | finished in 5532ms
Comment 3•11 years ago
|
||
How frequent is it? I'm wondering if it is frequent enough to be debugged without too much pain on try, because I'm not sure how feasible it is to run it locally on my Unagi, but if I can do that, great.
Updated•11 years ago
|
Flags: needinfo?(ryanvm)
Reporter | ||
Comment 4•11 years ago
|
||
Judging by the logs links posted here (which are all known instances I'm aware of), roughly 1-2x daily so far. But keep in mind that I wouldn't expect many runs over the weekend. We'll see how often we hit it today once the volume starts picking up again. I would expect semi-frequently. Stay tuned for the TBPLbot comments :)
Flags: needinfo?(ryanvm)
Reporter | ||
Comment 5•11 years ago
|
||
https://tbpl.mozilla.org/php/getParsedLog.php?id=25828053&tree=Mozilla-Inbound
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Reporter | ||
Comment 10•11 years ago
|
||
(In reply to Paul Adenot (:padenot) from comment #3) > How frequent is it? Looks like it's good for at least a few hits a day.
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment 13•11 years ago
|
||
https://tbpl.mozilla.org/php/getParsedLog.php?id=25897393&tree=Mozilla-Inbound#error0
Comment 14•11 years ago
|
||
https://tbpl.mozilla.org/php/getParsedLog.php?id=25910394&tree=Birch#error0
Comment 15•11 years ago
|
||
https://tbpl.mozilla.org/php/getParsedLog.php?id=25918476&tree=Mozilla-Inbound
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment hidden (Legacy TBPL/Treeherder Robot) |
Reporter | ||
Comment 33•11 years ago
|
||
Disabled for now. https://hg.mozilla.org/integration/mozilla-inbound/rev/8f104f9e0e5b
Whiteboard: [test disabled][leave open]
Comment hidden (Legacy TBPL/Treeherder Robot) |
Reporter | ||
Comment 35•11 years ago
|
||
https://hg.mozilla.org/mozilla-central/rev/8f104f9e0e5b
Comment hidden (Legacy TBPL/Treeherder Robot) |
Comment 37•11 years ago
|
||
Morphing summary to avoid false positives with all the other "command timed out: 1200 seconds without output, attempting to kill" failure modes.
Summary: Intermittent B2G "command timed out: 1200 seconds without output, attempting to kill" while running test_autoplay_contentEditable.html → Fix intermittent B2G timeouts during test_autoplay_contentEditable.html and re-enable it
Whiteboard: [test disabled][leave open] → [test disabled]
Reporter | ||
Comment 38•10 years ago
|
||
This test runs fine on all platforms. Will be re-enabling shortly.
Status: NEW → RESOLVED
Closed: 10 years ago
Resolution: --- → WORKSFORME
You need to log in
before you can comment on or make changes to this bug.
Description
•