Closed Bug 1006246 Opened 10 years ago Closed 8 years ago

Intermittent test_clone_media_element.html | application timed out after 330 seconds with no output

Categories

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

All
Android
defect

Tracking

()

RESOLVED WORKSFORME

People

(Reporter: KWierso, Assigned: jwwang)

References

Details

(Keywords: intermittent-failure)

https://tbpl.mozilla.org/php/getParsedLog.php?id=39083352&tree=Mozilla-Inbound
Android 2.2 Tegra mozilla-inbound opt test mochitest-3 on 2014-05-05 15:57:17 PDT for push 695049af7654

slave: tegra-148



10855 INFO TEST-START | /tests/content/media/test/test_clone_media_element.html
10856 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Started Mon May 05 2014 16:04:26 GMT+0000 (GMT) (1399305866.484s)
10857 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started small-shot.ogg-0] Length of array should match number of running tests
10858 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load small-shot.ogg-0
10859 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | small-shot.ogg-0 metadata loaded.
10860 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned small-shot.ogg-0 start loading.
10861 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | cloned small-shot.ogg-0 ended.
10862 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [finished small-shot.ogg-0] Length of array should match number of running tests
10863 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started r11025_s16_c1.wav-4] Length of array should match number of running tests
10864 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load r11025_s16_c1.wav-4
10865 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | r11025_s16_c1.wav-4 metadata loaded.
10866 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned r11025_s16_c1.wav-4 start loading.
10867 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | cloned r11025_s16_c1.wav-4 ended.
10868 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [finished r11025_s16_c1.wav-4] Length of array should match number of running tests
10869 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started 320x240.ogv-5] Length of array should match number of running tests
10870 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load 320x240.ogv-5
10871 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | 320x240.ogv-5 metadata loaded.
10872 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned 320x240.ogv-5 start loading.
10873 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | cloned 320x240.ogv-5 ended.
10874 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [finished 320x240.ogv-5] Length of array should match number of running tests
10875 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started seek.webm-6] Length of array should match number of running tests
10876 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load seek.webm-6
10877 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | seek.webm-6 metadata loaded.
10878 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned seek.webm-6 start loading.
10879 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | cloned seek.webm-6 ended.
10880 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [finished seek.webm-6] Length of array should match number of running tests
10881 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started vp9.webm-7] Length of array should match number of running tests
10882 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load vp9.webm-7
10883 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | vp9.webm-7 metadata loaded.
10884 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned vp9.webm-7 start loading.
10885 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | cloned vp9.webm-7 ended.
10886 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [finished vp9.webm-7] Length of array should match number of running tests
10887 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started detodos.opus-8] Length of array should match number of running tests
10888 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load detodos.opus-8
10889 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | detodos.opus-8 metadata loaded.
10890 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned detodos.opus-8 start loading.
10891 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | cloned detodos.opus-8 ended.
10892 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [finished detodos.opus-8] Length of array should match number of running tests
10893 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started big.wav-11] Length of array should match number of running tests
10894 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load big.wav-11
10895 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | big.wav-11 metadata loaded.
10896 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned big.wav-11 start loading.
10897 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | cloned big.wav-11 ended.
10898 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [finished big.wav-11] Length of array should match number of running tests
10899 INFO TEST-PASS | /tests/content/media/test/test_clone_media_element.html | [started seek.ogv-12] Length of array should match number of running tests
10900 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | Trying to load seek.ogv-12
10901 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | seek.ogv-12 metadata loaded.
10902 INFO TEST-INFO | /tests/content/media/test/test_clone_media_element.html | cloned seek.ogv-12 start loading.

TEST-UNEXPECTED-FAIL | /tests/content/media/test/test_clone_media_element.html | application timed out after 330 seconds with no output
Assignee: nobody → jwwang
(In reply to TBPL Robot from comment #2)
> philor
> https://tbpl.mozilla.org/php/getParsedLog.php?id=39252257&tree=Mozilla-
> Inbound
> Android 4.0 Panda mozilla-inbound debug test mochitest-3 on 2014-05-07
> 20:21:20
> revision: 1b8db8e3facb
> slave: panda-0082

20:46:23     INFO -  05-07 20:40:33.765 I/GeckoDump( 2268): 899 INFO TEST-END | /tests/content/media/test/test_chaining.html | finished in 26722ms
20:46:23     INFO -  05-07 20:40:33.804 I/Gecko   ( 2268): [2268] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file /builds/slave/m-in-and-d-0000000000000000000/build/content/html/content/src/HTMLVideoElement.cpp, line 302
20:46:23     INFO -  05-07 20:40:33.812 I/Gecko   ( 2268): void mozilla::AndroidBridge::HandleGeckoMessage(JSContext*, JS::HandleObject)
20:46:23     INFO -  05-07 20:40:33.820 D/GeckoTabs( 2268): handleMessage: SessionHistory:New
20:46:23     INFO -  05-07 20:40:33.820 I/Gecko   ( 2268): void mozilla::AndroidBridge::HandleGeckoMessage(JSContext*, JS::HandleObject)
20:46:23     INFO -  05-07 20:40:33.820 D/GeckoTabs( 2268): handleMessage: SessionHistory:Purge
20:46:23     INFO -  05-07 20:40:33.820 I/Gecko   ( 2268): ++DOMWINDOW == 9 (0x689cde00) [pid = 2268] [serial = 895] [outer = 0x6cd39e00]
20:46:23     INFO -  05-07 20:40:33.859 I/Gecko   ( 2268): [2268] WARNING: Failed to unlock the wakelock.: '!rv.Failed()', file /builds/slave/m-in-and-d-0000000000000000000/build/content/html/content/src/HTMLMediaElement.cpp, line 2265
20:46:23     INFO -  05-07 20:40:33.867 E/GeckoConsole( 2268): [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html" line: 0}]
20:46:23     INFO -  05-07 20:40:33.882 I/GeckoDump( 2268): 900 INFO TEST-START | /tests/content/media/test/test_clone_media_element.html
20:46:23     INFO -  05-07 20:40:33.921 I/Gecko   ( 2268): ++DOMWINDOW == 10 (0x68afbc00) [pid = 2268] [serial = 896] [outer = 0x6cd39e00]
20:46:23     INFO -  05-07 20:40:33.953 I/SUTAgentAndroid( 1901): 10.12.128.17 : activity
20:46:23     INFO -  05-07 20:40:33.976 I/SUTAgentAndroid( 1901): 10.12.128.17 : isdir /mnt/sdcard/tests/logs
20:46:23     INFO -  05-07 20:40:33.992 I/SUTAgentAndroid( 1901): 10.12.128.17 : cd /mnt/sdcard/tests/logs
20:46:23     INFO -  05-07 20:40:34.007 I/SUTAgentAndroid( 1901): 10.12.128.17 : ls
20:46:23     INFO -  05-07 20:40:34.031 I/SUTAgentAndroid( 1901): 10.12.128.17 : pull /mnt/sdcard/tests/logs/mochitest.log 63045
20:46:23     INFO -  05-07 20:40:34.140 E/GeckoConsole( 2268): [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:34.734 V/tiny_hw ( 1293): out_standby(0x1ae4160) closing PCM
20:46:23     INFO -  05-07 20:40:34.804 I/Gecko   ( 2268): nsWindow::SetFocus: can't set focus without raising, ignoring aRaise = false!
20:46:23     INFO -  05-07 20:40:34.835 I/Gecko   ( 2268): --DOMWINDOW == 9 (0x689cde00) [pid = 2268] [serial = 895] [outer = 0x0] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
20:46:23     INFO -  05-07 20:40:35.539 I/Gecko   ( 2268): --DOMWINDOW == 8 (0x68956200) [pid = 2268] [serial = 894] [outer = 0x0] [url = http://mochi.test:8888/tests/content/media/test/test_chaining.html]
20:46:23     INFO -  05-07 20:40:35.640 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:35.648 E/libOpenSLES( 2268): pAudioSrc: samplesPerSec=11127000
20:46:23     INFO -  05-07 20:40:35.648 W/libOpenSLES( 2268): Leaving Engine::CreateAudioPlayer (SL_RESULT_CONTENT_UNSUPPORTED)
20:46:23     INFO -  05-07 20:40:35.976 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:37.406 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:37.421 V/tiny_hw ( 1293): out_write(0x1ae4160) opening PCM
20:46:23     INFO -  05-07 20:40:38.554 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:40.406 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:40.703 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:41.531 V/tiny_hw ( 1293): out_standby(0x1ae4160) closing PCM
20:46:23     INFO -  05-07 20:40:42.242 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:46.289 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:47.750 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:49.820 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:51.125 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:51.140 V/tiny_hw ( 1293): out_write(0x1ae4160) opening PCM
20:46:23     INFO -  05-07 20:40:54.062 I/SUTAgentAndroid( 1901): 10.12.128.17 : activity
20:46:23     INFO -  05-07 20:40:54.289 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:40:55.671 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:41:05.312 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:41:06.781 E/GeckoConsole( 2268): [JavaScript Warning: "Invalid URI. Load of media resource  failed." {file: "http://mochi.test:8888/tests/content/media/test/test_clone_media_element.html" line: 0}]
20:46:23     INFO -  05-07 20:41:08.265 V/tiny_hw ( 1293): out_standby(0x1ae4160) closing PCM
20:46:23     INFO -  05-07 20:41:14.085 I/SUTAgentAndroid( 1901): 10.12.128.17 : activity
20:46:23     INFO -  05-07 20:41:34.109 I/SUTAgentAndroid( 1901): 10.12.128.17 : activity
20:46:23     INFO -  05-07 20:40:35.648 E/libOpenSLES( 2268): pAudioSrc: samplesPerSec=11127000
20:46:23     INFO -  05-07 20:40:35.648 W/libOpenSLES( 2268): Leaving Engine::CreateAudioPlayer (SL_RESULT_CONTENT_UNSUPPORTED)

frame rate = 11127000 not supported on Android or B2G.
Depends on: 1008079
Status: NEW → RESOLVED
Closed: 9 years ago
Resolution: --- → WORKSFORME
Reopening, today I hit this failure on try.
Status: RESOLVED → REOPENED
Resolution: WORKSFORME → ---
Priority: -- → P5
Component: Audio/Video → Audio/Video: Playback
The code has changed a lot ever since last timeout. Please open a new bug when timeout happens again.
Status: REOPENED → RESOLVED
Closed: 9 years ago8 years ago
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.